You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/02/25 20:02:43 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 339 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/339/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.component.CustomHighlightComponentTest
Error Message:
There are still nodes recoverying - waited for 45 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 45 seconds
at __randomizedtesting.SeedInfo.seed([98F5982449109BD5]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
at org.apache.solr.handler.component.CustomHighlightComponentTest.setupCluster(CustomHighlightComponentTest.java:134)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14124 lines...]
[junit4] Suite: org.apache.solr.handler.component.CustomHighlightComponentTest
[junit4] 2> 508274 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 508274 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 508275 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/data-dir-60-001
[junit4] 2> 508275 WARN (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 508275 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 508276 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 508276 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 508277 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001
[junit4] 2> 508277 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 508277 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 508277 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 508278 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 508377 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 40445
[junit4] 2> 508377 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:40445
[junit4] 2> 508377 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40445
[junit4] 2> 508377 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40445
[junit4] 2> 508379 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508380 INFO (zkConnectionManagerCallback-4526-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508380 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508383 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508383 INFO (zkConnectionManagerCallback-4528-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508383 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508384 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508385 INFO (zkConnectionManagerCallback-4530-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508385 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 508487 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2b4bca40{/solr,null,AVAILABLE}
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 508488 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ce687c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:40045}
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.e.j.s.Server Started @508520ms
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9e09169{/solr,null,AVAILABLE}
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40445/solr, hostPort=40045}
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f5f6d24{/solr,null,AVAILABLE}
[junit4] 2> 508489 ERROR (jetty-launcher-4531-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b49b8a7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42045}
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.2
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.e.j.s.Server Started @508521ms
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40445/solr, hostPort=42045}
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-25T19:14:16.273Z
[junit4] 2> 508489 ERROR (jetty-launcher-4531-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.2
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 508489 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-25T19:14:16.273Z
[junit4] 2> 508491 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508492 INFO (zkConnectionManagerCallback-4533-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@57439d45{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37041}
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.e.j.s.Server Started @508523ms
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40445/solr, hostPort=37041}
[junit4] 2> 508492 ERROR (jetty-launcher-4531-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.2
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 508492 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-25T19:14:16.276Z
[junit4] 2> 508493 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 508493 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 508494 INFO (zkConnectionManagerCallback-4535-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508494 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508497 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 508497 INFO (zkConnectionManagerCallback-4537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 508497 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 508515 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 508517 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 508517 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 508518 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 508518 WARN (jetty-launcher-4531-thread-3-SendThread(127.0.0.1:40445)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103918a1cb40005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 509143 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 509144 WARN (jetty-launcher-4531-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40bf033d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509144 WARN (jetty-launcher-4531-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40bf033d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509147 WARN (jetty-launcher-4531-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3dc860ec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509147 WARN (jetty-launcher-4531-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3dc860ec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509149 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40445/solr
[junit4] 2> 509150 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509154 INFO (zkConnectionManagerCallback-4554-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509154 INFO (jetty-launcher-4531-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509258 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509270 INFO (zkConnectionManagerCallback-4556-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509270 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509351 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40045_solr
[junit4] 2> 509351 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.Overseer Overseer (id=73062041341132807-127.0.0.1:40045_solr-n_0000000000) starting
[junit4] 2> 509358 INFO (OverseerStateUpdate-73062041341132807-127.0.0.1:40045_solr-n_0000000000) [n:127.0.0.1:40045_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40045_solr
[junit4] 2> 509370 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40045_solr
[junit4] 2> 509373 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 509373 WARN (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 509381 INFO (zkCallback-4555-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 509388 INFO (jetty-launcher-4531-thread-1) [n:127.0.0.1:40045_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node1
[junit4] 2> 509487 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 509488 WARN (jetty-launcher-4531-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@742115be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509490 WARN (jetty-launcher-4531-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@742115be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509493 WARN (jetty-launcher-4531-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@514e3e80[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509493 WARN (jetty-launcher-4531-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@514e3e80[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509494 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40445/solr
[junit4] 2> 509495 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509498 INFO (zkConnectionManagerCallback-4574-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509498 INFO (jetty-launcher-4531-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509586 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 509587 WARN (jetty-launcher-4531-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@663e1898[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509587 WARN (jetty-launcher-4531-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@663e1898[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509589 WARN (jetty-launcher-4531-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7675e5e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509589 WARN (jetty-launcher-4531-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7675e5e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 509590 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40445/solr
[junit4] 2> 509591 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509592 INFO (zkConnectionManagerCallback-4582-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509592 INFO (jetty-launcher-4531-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509600 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509600 INFO (zkConnectionManagerCallback-4584-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509600 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509605 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 509608 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37041_solr as DOWN
[junit4] 2> 509608 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 509608 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37041_solr
[junit4] 2> 509609 INFO (zkCallback-4555-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 509609 INFO (zkCallback-4583-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 509610 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 509611 WARN (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 509625 INFO (jetty-launcher-4531-thread-3) [n:127.0.0.1:37041_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node3
[junit4] 2> 509700 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509701 INFO (zkConnectionManagerCallback-4592-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509701 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509705 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 509717 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42045_solr as DOWN
[junit4] 2> 509718 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 509718 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42045_solr
[junit4] 2> 509719 INFO (zkCallback-4555-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 509719 INFO (zkCallback-4583-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 509720 INFO (zkCallback-4591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 509721 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 509721 WARN (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 509731 INFO (jetty-launcher-4531-thread-2) [n:127.0.0.1:42045_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node2
[junit4] 2> 509741 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 509741 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 509742 INFO (zkConnectionManagerCallback-4603-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 509742 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 509743 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 509744 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40445/solr ready
[junit4] 2> 509750 INFO (qtp1377924086-9273) [n:127.0.0.1:40045_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=25122442-d282-4332-869b-82eef61d9674&collection.configName=conf&maxShardsPerNode=2&name=collection24&nrtReplicas=2&action=CREATE&numShards=3&perReplicaState=true&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 509752 INFO (qtp1377924086-9274) [n:127.0.0.1:40045_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=25122442-d282-4332-869b-82eef61d9674&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 509752 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection24
[junit4] 2> 509756 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42045_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42045/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509758 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:37041_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37041/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509761 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard2_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:40045_solr",
[junit4] 2> "base_url":"http://127.0.0.1:40045/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509763 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard2_replica_n7",
[junit4] 2> "node_name":"127.0.0.1:42045_solr",
[junit4] 2> "base_url":"http://127.0.0.1:42045/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509765 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard3_replica_n9",
[junit4] 2> "node_name":"127.0.0.1:37041_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37041/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509767 INFO (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection24_shard3_replica_n11",
[junit4] 2> "node_name":"127.0.0.1:40045_solr",
[junit4] 2> "base_url":"http://127.0.0.1:40045/solr",
[junit4] 2> "collection":"collection24",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 509780 INFO (qtp1377924086-9272) [n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651006742880&coreNodeName=core_node6&name=collection24_shard2_replica_n5&action=CREATE&numShards=3&shard=shard2&wt=javabin} status=0 QTime=0
[junit4] 2> 509780 INFO (qtp1501849605-9286) [n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651004628547&coreNodeName=core_node4&name=collection24_shard1_replica_n3&action=CREATE&numShards=3&shard=shard1&wt=javabin} status=0 QTime=0
[junit4] 2> 509780 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651004628547&coreNodeName=core_node4&name=collection24_shard1_replica_n3&action=CREATE&numShards=3&shard=shard1&wt=javabin
[junit4] 2> 509780 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651006742880&coreNodeName=core_node6&name=collection24_shard2_replica_n5&action=CREATE&numShards=3&shard=shard2&wt=javabin
[junit4] 2> 509780 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 509780 INFO (qtp1377924086-9272) [n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651012857584&coreNodeName=core_node12&name=collection24_shard3_replica_n11&action=CREATE&numShards=3&shard=shard3&wt=javabin} status=0 QTime=0
[junit4] 2> 509781 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651012857584&coreNodeName=core_node12&name=collection24_shard3_replica_n11&action=CREATE&numShards=3&shard=shard3&wt=javabin
[junit4] 2> 509781 INFO (qtp361649254-9282) [n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651002268553&coreNodeName=core_node2&name=collection24_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin} status=0 QTime=1
[junit4] 2> 509781 INFO (qtp1501849605-9288) [n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651010831322&coreNodeName=core_node10&name=collection24_shard3_replica_n9&action=CREATE&numShards=3&shard=shard3&wt=javabin} status=0 QTime=1
[junit4] 2> 509782 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651002268553&coreNodeName=core_node2&name=collection24_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin
[junit4] 2> 509783 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651010831322&coreNodeName=core_node10&name=collection24_shard3_replica_n9&action=CREATE&numShards=3&shard=shard3&wt=javabin
[junit4] 2> 509782 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651008821498&coreNodeName=core_node8&name=collection24_shard2_replica_n7&action=CREATE&numShards=3&shard=shard2&wt=javabin
[junit4] 2> 509782 INFO (qtp361649254-9287) [n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=collection24&version=2&replicaType=NRT&async=25122442-d282-4332-869b-82eef61d967415326651008821498&coreNodeName=core_node8&name=collection24_shard2_replica_n7&action=CREATE&numShards=3&shard=shard2&wt=javabin} status=0 QTime=0
[junit4] 2> 509795 INFO (qtp361649254-9282) [n:127.0.0.1:42045_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 25122442-d282-4332-869b-82eef61d967415326651002268553
[junit4] 2> 509795 INFO (qtp361649254-9282) [n:127.0.0.1:42045_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=25122442-d282-4332-869b-82eef61d967415326651002268553&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 509803 INFO (zkCallback-4583-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection24/state.json] for collection [collection24] has occurred - updating... (live nodes size: [3])
[junit4] 2> 509803 INFO (zkCallback-4583-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection24/state.json] for collection [collection24] has occurred - updating... (live nodes size: [3])
[junit4] 2> 509806 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509812 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509830 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509832 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509832 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509834 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509834 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard2_replica_n5' using configuration from configset conf, trusted=true
[junit4] 2> 509835 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.SolrCore [[collection24_shard2_replica_n5] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node1/collection24_shard2_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node1/collection24_shard2_replica_n5/data/]
[junit4] 2> 509839 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509841 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509842 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509842 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard3_replica_n11' using configuration from configset conf, trusted=true
[junit4] 2> 509843 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509843 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard2_replica_n7' using configuration from configset conf, trusted=true
[junit4] 2> 509843 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.SolrCore [[collection24_shard2_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node2/collection24_shard2_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node2/collection24_shard2_replica_n7/data/]
[junit4] 2> 509845 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509845 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509847 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509847 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard1_replica_n3' using configuration from configset conf, trusted=true
[junit4] 2> 509847 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.SolrCore [[collection24_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node3/collection24_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node3/collection24_shard1_replica_n3/data/]
[junit4] 2> 509848 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.SolrCore [[collection24_shard3_replica_n11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node1/collection24_shard3_replica_n11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node1/collection24_shard3_replica_n11/data/]
[junit4] 2> 509862 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509864 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509864 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 509864 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection24_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node2/collection24_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node2/collection24_shard1_replica_n1/data/]
[junit4] 2> 509890 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
[junit4] 2> 509893 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509893 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509894 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509894 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509899 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.s.IndexSchema Schema name=cloud-dynamic
[junit4] 2> 509902 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 509902 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.CoreContainer Creating SolrCore 'collection24_shard3_replica_n9' using configuration from configset conf, trusted=true
[junit4] 2> 509906 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.SolrCore [[collection24_shard3_replica_n9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node3/collection24_shard3_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.component.CustomHighlightComponentTest_98F5982449109BD5-001/tempDir-001/node3/collection24_shard3_replica_n9/data/]
[junit4] 2> 509906 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509907 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509907 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509907 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745203798016
[junit4] 2> 509920 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509921 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509921 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509921 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509922 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509923 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509923 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509923 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745220575232
[junit4] 2> 509930 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509930 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509931 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509931 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509931 INFO (searcherExecutor-4605-thread-1-processing-x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 r:core_node6 CREATE n:127.0.0.1:40045_solr c:collection24 s:shard2) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.SolrCore [collection24_shard2_replica_n5] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509931 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509931 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509932 INFO (searcherExecutor-4609-thread-1-processing-x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 r:core_node8 CREATE n:127.0.0.1:42045_solr c:collection24 s:shard2) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.SolrCore [collection24_shard2_replica_n7] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509932 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509932 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509933 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509933 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509934 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509934 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509937 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509937 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509937 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509937 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509937 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745235255296
[junit4] 2> 509945 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509945 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509945 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745243643904
[junit4] 2> 509946 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509949 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 509949 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 509949 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 509950 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 509950 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509950 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509950 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745248886784
[junit4] 2> 509951 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 509952 INFO (searcherExecutor-4613-thread-1-processing-x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 r:core_node2 CREATE n:127.0.0.1:42045_solr c:collection24 s:shard1) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.SolrCore [collection24_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509956 INFO (searcherExecutor-4611-thread-1-processing-x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 r:core_node4 CREATE n:127.0.0.1:37041_solr c:collection24 s:shard1) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.SolrCore [collection24_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509956 INFO (searcherExecutor-4607-thread-1-processing-x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 r:core_node12 CREATE n:127.0.0.1:40045_solr c:collection24 s:shard3) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.SolrCore [collection24_shard3_replica_n11] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509958 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 509958 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 509958 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692695745257275392
[junit4] 2> 509961 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard2 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 509961 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 509961 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard2
[junit4] 2> 509963 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard2 to Terms{values={core_node6=0, core_node8=0}, version=1}
[junit4] 2> 509963 INFO (parallelCoreAdminExecutor-4564-thread-1-processing-n:127.0.0.1:40045_solr x:collection24_shard2_replica_n5 25122442-d282-4332-869b-82eef61d967415326651006742880 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard2 r:core_node6 x:collection24_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard2
[junit4] 2> 509966 INFO (searcherExecutor-4620-thread-1-processing-x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 r:core_node10 CREATE n:127.0.0.1:37041_solr c:collection24 s:shard3) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.SolrCore [collection24_shard3_replica_n9] Registered new searcher autowarm time: 0 ms
[junit4] 2> 509981 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 509981 INFO (parallelCoreAdminExecutor-4588-thread-1-processing-n:127.0.0.1:37041_solr x:collection24_shard1_replica_n3 25122442-d282-4332-869b-82eef61d967415326651004628547 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard1 r:core_node4 x:collection24_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard1
[junit4] 2> 509982 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard3 to Terms{values={core_node12=0}, version=0}
[junit4] 2> 509982 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard3
[junit4] 2> 509982 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 509982 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard1
[junit4] 2> 509983 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 509983 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 509983 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42045/solr/collection24_shard2_replica_n7/
[junit4] 2> 509984 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard2_replica_n7 url=http://127.0.0.1:42045/solr START replicas=[http://127.0.0.1:40045/solr/collection24_shard2_replica_n5/] nUpdates=100
[junit4] 2> 509986 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection24/terms/shard3 to Terms{values={core_node10=0, core_node12=0}, version=1}
[junit4] 2> 509986 INFO (parallelCoreAdminExecutor-4588-thread-2-processing-n:127.0.0.1:37041_solr x:collection24_shard3_replica_n9 25122442-d282-4332-869b-82eef61d967415326651010831322 CREATE) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection24/leaders/shard3
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42045/solr/collection24_shard1_replica_n1/
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40045/solr/collection24_shard3_replica_n11/
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard1_replica_n1 url=http://127.0.0.1:42045/solr START replicas=[http://127.0.0.1:37041/solr/collection24_shard1_replica_n3/] nUpdates=100
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard3_replica_n11 url=http://127.0.0.1:40045/solr START replicas=[http://127.0.0.1:37041/solr/collection24_shard3_replica_n9/] nUpdates=100
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_replica_n11 25122442-d282-4332-869b-82eef61d967415326651012857584 CREATE) [n:127.0.0.1:40045_solr c:collection24 s:shard3 r:core_node12 x:collection24_shard3_replica_n11 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard3_replica_n11 url=http://127.0.0.1:40045/solr DONE. We have no versions. sync failed.
[junit4] 2> 509991 INFO (parallelCoreAdminExecutor-4596-thread-2-processing-n:127.0.0.1:42045_solr x:collection24_shard2_replica_n7 25122442-d282-4332-869b-82eef61d967415326651008821498 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard2 r:core_node8 x:collection24_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard2_replica_n7 url=http://127.0.0.1:42045/solr DONE. We have no versions. sync failed.
[junit4] 2> 509992 INFO (parallelCoreAdminExecutor-4596-thread-1-processing-n:127.0.0.1:42045_solr x:collection24_shard1_replica_n1 25122442-d282-4332-869b-82eef61d967415326651002268553 CREATE) [n:127.0.0.1:42045_solr c:collection24 s:shard1 r:core_node2 x:collection24_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=collection24_shard1_replica_n1 url=http://127.0.0.1:42045/solr DONE. We have no versions. sync failed.
[junit4] 2> 509993 INFO (qtp1501849605-9291) [n:127.0.0.1:37041_solr c:collection24 s:shard3 r:core_node10 x:collection24_shard3_replica_n9 ] o.a.s.c.S.Request [collection24_shard3_replica_n9] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 509993 INFO (parallelCoreAdminExecutor-4564-thread-2-processing-n:127.0.0.1:40045_solr x:collection24_shard3_repl
[...truncated too long message...]
ThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.OverseerTaskProcessor Resetting task: /overseer/collection-queue-work/qn-0000000000, requestid: 25122442-d282-4332-869b-82eef61d9674, taskKey: collection24
[junit4] 2> 600951 ERROR (OverseerThreadFactory-4563-thread-1-processing-n:127.0.0.1:40045_solr) [n:127.0.0.1:40045_solr ] o.a.s.c.OverseerTaskProcessor :org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-map-running/mn-25122442-d282-4332-869b-82eef61d9674
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:2001)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$delete$1(SolrZkClient.java:264)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:263)
[junit4] 2> at org.apache.solr.cloud.DistributedMap.remove(DistributedMap.java:98)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.resetTaskWithException(OverseerTaskProcessor.java:608)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:574)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 600951 INFO (OverseerAutoScalingTriggerThread-73062041341132807-127.0.0.1:40045_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 600952 INFO (jetty-closer-4640-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73062041341132807-127.0.0.1:40045_solr-n_0000000000) closing
[junit4] 2> 600952 INFO (closeThreadPool-4657-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73062041341132807-127.0.0.1:40045_solr-n_0000000000) closing
[junit4] 2> 600953 INFO (jetty-closer-4640-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5ce687c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 600953 INFO (jetty-closer-4640-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2b4bca40{/solr,null,STOPPED}
[junit4] 2> 600953 INFO (jetty-closer-4640-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 600954 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 600954 WARN (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker-SendThread(127.0.0.1:40445)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103918a1cb40000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 601056 WARN (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker-SendThread(127.0.0.1:40445)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103918a1cb40001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x103918a1cb40001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 601157 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/collection24/terms/shard2
[junit4] 2> 5 /solr/collections/collection24/terms/shard3
[junit4] 2> 5 /solr/collections/collection24/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/collection24/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 31 /solr/collections/collection24/state.json
[junit4] 2> 15 /solr/overseer/queue
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 601159 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:40445
[junit4] 2> 601159 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40445
[junit4] 2> 601159 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40445
[junit4] 2> 601159 INFO (SUITE-CustomHighlightComponentTest-seed#[98F5982449109BD5]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Feb 25, 2021 7:15:49 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=870, maxMBSortInHeap=7.632329992932715, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=es-BO, timezone=Antarctica/Davis
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=125991416,total=524812288
[junit4] 2> NOTE: All tests run in this JVM: [MoveReplicaTest, TestFieldCacheReopen, TestOverriddenPrefixQueryForCustomFieldType, TestReloadAndDeleteDocs, TestSchemaVersionResource, OpenExchangeRatesOrgProviderTest, SuggesterFSTTest, TestDFRSimilarityFactory, TestSubQueryTransformerCrossCore, SpatialFilterTest, TestBadConfig, CategoryRoutedAliasUpdateProcessorTest, SecurityHeadersTest, TestLegacyBM25SimilarityFactory, RegexBoostProcessorTest, HighlighterConfigTest, RemoteQueryErrorTest, RangeFacetCloudTest, LeaderElectionTest, TestRecoveryHdfs, TestImpersonationWithHadoopAuth, VersionInfoTest, SystemLogListenerTest, TestDynamicFieldResource, TestEmbeddedSolrServerConstructors, EchoParamsTest, AnalyticsMergeStrategyTest, LeaderVoteWaitTimeoutTest, HdfsRecoveryZkTest, HdfsLockFactoryTest, ExplicitHLLTest, TestManagedSynonymFilterFactory, TestCollectionAPIs, TestWordDelimiterFilterFactory, DataDrivenBlockJoinTest, TestCloudNestedDocsSort, TestSafeXMLParsing, TestExternalRoleRuleBasedAuthorizationPlugin, BJQFilterAccessibleTest, TestNamedUpdateProcessors, IndexSchemaTest, TriggerIntegrationTest, FuzzySearchTest, TestConfigSetImmutable, TestInPlaceUpdatesRequiredField, MetricTriggerTest, TestRestManager, V2StandaloneTest, SystemCollectionCompatTest, CleanupOldIndexTest, TestDynamicLoadingUrl, OverseerStatusTest, SSLMigrationTest, TestRestoreCore, CdcrWithNodesRestartsTest, TestLegacyTerms, MigrateRouteKeyTest, SolrSlf4jReporterTest, TestSchemaField, CustomHighlightComponentTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CustomHighlightComponentTest -Dtests.seed=98F5982449109BD5 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-BO -Dtests.timezone=Antarctica/Davis -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | CustomHighlightComponentTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 45 seconds
[junit4] > at __randomizedtesting.SeedInfo.seed([98F5982449109BD5]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
[junit4] > at org.apache.solr.handler.component.CustomHighlightComponentTest.setupCluster(CustomHighlightComponentTest.java:134)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [265/923 (1!)] on J2 in 93.54s, 0 tests, 1 failure <<< FAILURES!
[...truncated 53027 lines...]