You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/08/25 17:50:49 UTC
[JENKINS] Lucene-Solr-6.6-Linux (64bit/jdk1.8.0_144) - Build # 94 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/94/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Something is broken in the assert for no shards using the same indexDir - probably something was changed in the attributes published in the MBean of SolrCore : {}
Stack Trace:
java.lang.AssertionError: Something is broken in the assert for no shards using the same indexDir - probably something was changed in the attributes published in the MBean of SolrCore : {}
at __randomizedtesting.SeedInfo.seed([9DD4B01A261452B0:D5A1C4AE20277D25]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.checkNoTwoShardsUseTheSameIndexDir(CollectionsAPIDistributedZkTest.java:646)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:524)
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:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 11626 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/init-core-data-001
[junit4] 2> 527913 WARN (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=44 numCloses=44
[junit4] 2> 527913 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 527914 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 527915 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001
[junit4] 2> 527915 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 527915 INFO (Thread-3179) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 527915 INFO (Thread-3179) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 527916 ERROR (Thread-3179) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 528015 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43593
[junit4] 2> 528020 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e1a60ab5a0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 528042 INFO (jetty-launcher-889-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 528043 INFO (jetty-launcher-889-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 528044 INFO (jetty-launcher-889-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 528044 INFO (jetty-launcher-889-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 528044 INFO (jetty-launcher-889-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@462c3c19{/solr,null,AVAILABLE}
[junit4] 2> 528046 INFO (jetty-launcher-889-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60db9950{/solr,null,AVAILABLE}
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6b0b1b1f{SSL,[ssl, http/1.1]}{127.0.0.1:45469}
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.e.j.s.Server Started @530070ms
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45469}
[junit4] 2> 528048 ERROR (jetty-launcher-889-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.1
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 528048 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T17:11:36.670Z
[junit4] 2> 528055 INFO (jetty-launcher-889-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@612bb9a1{/solr,null,AVAILABLE}
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@65750cfc{SSL,[ssl, http/1.1]}{127.0.0.1:45211}
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.e.j.s.Server Started @530078ms
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45211}
[junit4] 2> 528056 ERROR (jetty-launcher-889-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.1
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 528056 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T17:11:36.678Z
[junit4] 2> 528058 INFO (jetty-launcher-889-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@712034a3{SSL,[ssl, http/1.1]}{127.0.0.1:35351}
[junit4] 2> 528058 INFO (jetty-launcher-889-thread-2) [ ] o.e.j.s.Server Started @530080ms
[junit4] 2> 528058 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35351}
[junit4] 2> 528059 ERROR (jetty-launcher-889-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 528059 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.1
[junit4] 2> 528059 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 528059 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 528059 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T17:11:36.681Z
[junit4] 2> 528060 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 528065 INFO (jetty-launcher-889-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@287f97d9{/solr,null,AVAILABLE}
[junit4] 2> 528066 INFO (jetty-launcher-889-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d7b976d{SSL,[ssl, http/1.1]}{127.0.0.1:41407}
[junit4] 2> 528066 INFO (jetty-launcher-889-thread-4) [ ] o.e.j.s.Server Started @530088ms
[junit4] 2> 528066 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41407}
[junit4] 2> 528067 ERROR (jetty-launcher-889-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 528067 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.6.1
[junit4] 2> 528067 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 528067 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 528067 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-08-25T17:11:36.689Z
[junit4] 2> 528067 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 528073 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 528073 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 528073 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 528074 INFO (jetty-launcher-889-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43593/solr
[junit4] 2> 528080 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 528081 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 528081 INFO (jetty-launcher-889-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43593/solr
[junit4] 2> 528081 INFO (jetty-launcher-889-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43593/solr
[junit4] 2> 528087 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 528089 INFO (jetty-launcher-889-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43593/solr
[junit4] 2> 528099 INFO (jetty-launcher-889-thread-3) [n:127.0.0.1:45469_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 528099 INFO (jetty-launcher-889-thread-3) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45469_solr
[junit4] 2> 528100 INFO (jetty-launcher-889-thread-3) [n:127.0.0.1:45469_solr ] o.a.s.c.Overseer Overseer (id=98545244342714376-127.0.0.1:45469_solr-n_0000000000) starting
[junit4] 2> 528108 INFO (jetty-launcher-889-thread-2) [n:127.0.0.1:35351_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 528109 INFO (jetty-launcher-889-thread-2) [n:127.0.0.1:35351_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35351_solr
[junit4] 2> 528116 INFO (jetty-launcher-889-thread-1) [n:127.0.0.1:45211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 528117 INFO (jetty-launcher-889-thread-1) [n:127.0.0.1:45211_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 528117 INFO (jetty-launcher-889-thread-1) [n:127.0.0.1:45211_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45211_solr
[junit4] 2> 528125 INFO (jetty-launcher-889-thread-3) [n:127.0.0.1:45469_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45469_solr
[junit4] 2> 528134 INFO (zkCallback-906-thread-1-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 528135 INFO (zkCallback-909-thread-1-processing-n:127.0.0.1:35351_solr) [n:127.0.0.1:35351_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 528135 INFO (OverseerStateUpdate-98545244342714376-127.0.0.1:45469_solr-n_0000000000) [n:127.0.0.1:45469_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 528138 INFO (jetty-launcher-889-thread-4) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 528138 INFO (jetty-launcher-889-thread-4) [n:127.0.0.1:41407_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 528139 INFO (jetty-launcher-889-thread-4) [n:127.0.0.1:41407_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41407_solr
[junit4] 2> 528140 INFO (zkCallback-906-thread-2-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 528143 INFO (zkCallback-912-thread-1-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 528143 INFO (zkCallback-909-thread-2-processing-n:127.0.0.1:35351_solr) [n:127.0.0.1:35351_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 528143 INFO (zkCallback-910-thread-1-processing-n:127.0.0.1:45211_solr) [n:127.0.0.1:45211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 528151 INFO (jetty-launcher-889-thread-2) [n:127.0.0.1:35351_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node4/.
[junit4] 2> 528185 INFO (jetty-launcher-889-thread-3) [n:127.0.0.1:45469_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node2/.
[junit4] 2> 528186 INFO (jetty-launcher-889-thread-1) [n:127.0.0.1:45211_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node1/.
[junit4] 2> 528218 INFO (jetty-launcher-889-thread-4) [n:127.0.0.1:41407_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node3/.
[junit4] 2> 528223 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 528224 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43593/solr ready
[junit4] 2> 528253 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[9DD4B01A261452B0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
[junit4] 2> 528253 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[9DD4B01A261452B0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 528312 INFO (qtp384063131-7119) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 528317 INFO (OverseerThreadFactory-2646-thread-1-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.CreateCollectionCmd Create collection collection
[junit4] 2> 528317 WARN (OverseerThreadFactory-2646-thread-1-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 10 on collection collection is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(4). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 528317 ERROR (OverseerThreadFactory-2646-thread-1-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerCollectionMessageHandler Collection: collection operation: create failed:org.apache.solr.common.SolrException: Cannot create collection collection. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 4. This allows a maximum of 4 to be created. Value of numShards is 2 and value of replicationFactor is 10. This requires 20 shards to be created (higher than the allowed number)
[junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
[junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:226)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[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> 528328 INFO (qtp384063131-7119) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2} status=400 QTime=16
[junit4] 2> 528330 INFO (TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[9DD4B01A261452B0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
[junit4] 2> 528354 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[9DD4B01A261452B0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet
[junit4] 2> 528354 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[9DD4B01A261452B0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 528357 INFO (qtp384063131-7125) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:45211/solr,https://127.0.0.1:45469/solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 528358 INFO (OverseerThreadFactory-2646-thread-2-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.CreateCollectionCmd Create collection nodeset_collection
[junit4] 2> 528358 INFO (OverseerCollectionConfigSetProcessor-98545244342714376-127.0.0.1:45469_solr-n_0000000000) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 528358 WARN (OverseerThreadFactory-2646-thread-2-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (nodeset_collection) without cores.
[junit4] 2> 528461 INFO (qtp384063131-7125) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 528462 INFO (qtp384063131-7125) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:45211/solr,https://127.0.0.1:45469/solr&wt=javabin&version=2} status=0 QTime=104
[junit4] 2> 528462 INFO (TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[9DD4B01A261452B0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet
[junit4] 2> 528485 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[9DD4B01A261452B0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
[junit4] 2> 528486 INFO (TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[9DD4B01A261452B0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 528488 INFO (qtp384063131-7126) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=nodeset_collection&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 528490 INFO (OverseerCollectionConfigSetProcessor-98545244342714376-127.0.0.1:45469_solr-n_0000000000) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 528490 INFO (OverseerThreadFactory-2646-thread-3-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 529193 INFO (qtp384063131-7126) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0 QTime=704
[junit4] 2> 529194 INFO (qtp384063131-7120) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=159b23e3-628f-4fb7-8ea7-1007ef05a6d8&replicationFactor=4&collection.configName=conf2&maxShardsPerNode=3&name=awhollynewcollection_0&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 529195 INFO (qtp384063131-7120) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d8&replicationFactor=4&collection.configName=conf2&maxShardsPerNode=3&name=awhollynewcollection_0&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529196 INFO (OverseerThreadFactory-2646-thread-4-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.CreateCollectionCmd Create collection awhollynewcollection_0
[junit4] 2> 529196 INFO (OverseerCollectionConfigSetProcessor-98545244342714376-127.0.0.1:45469_solr-n_0000000000) [n:127.0.0.1:45469_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 529298 INFO (zkCallback-919-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 529422 INFO (qtp384063131-7124) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica4&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 529422 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica4&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
[junit4] 2> 529422 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 529423 INFO (qtp384063131-7125) [n:127.0.0.1:35351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica4&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 529424 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica4&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
[junit4] 2> 529454 INFO (qtp556525585-7132) [n:127.0.0.1:45469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica2&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529454 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica2&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
[junit4] 2> 529455 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 529468 INFO (qtp1272079680-7152) [n:127.0.0.1:45211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica3&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529468 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica3&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
[junit4] 2> 529468 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 529472 INFO (qtp1272079680-7129) [n:127.0.0.1:45211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica3&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529472 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica3&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
[junit4] 2> 529476 INFO (qtp556525585-7131) [n:127.0.0.1:45469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica2&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529476 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica2&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
[junit4] 2> 529479 INFO (qtp1935959068-7143) [n:127.0.0.1:41407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica1&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529482 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica1&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
[junit4] 2> 529488 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 529498 INFO (qtp1935959068-7144) [n:127.0.0.1:41407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica1&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529498 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr ] o.a.s.h.a.CoreAdminOperation core create command async=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica1&action=CREATE&numShards=2&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
[junit4] 2> 529501 INFO (qtp556525585-7133) [n:127.0.0.1:45469_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588
[junit4] 2> 529501 INFO (qtp556525585-7133) [n:127.0.0.1:45469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 529602 INFO (zkCallback-912-thread-1-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 529602 INFO (zkCallback-906-thread-2-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 529602 INFO (zkCallback-919-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 529602 INFO (zkCallback-909-thread-2-processing-n:127.0.0.1:35351_solr) [n:127.0.0.1:35351_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 529606 INFO (zkCallback-910-thread-1-processing-n:127.0.0.1:45211_solr) [n:127.0.0.1:45211_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/awhollynewcollection_0/state.json] for collection [awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
[junit4] 2> 530434 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530436 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530456 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica4] Schema name=minimal
[junit4] 2> 530457 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica4] Schema name=minimal
[junit4] 2> 530459 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530459 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica4' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530459 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530459 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node4/awhollynewcollection_0_shard1_replica4], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node4/./awhollynewcollection_0_shard1_replica4/data/]
[junit4] 2> 530462 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530462 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530462 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica4' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530462 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530462 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node4/awhollynewcollection_0_shard2_replica4], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node4/./awhollynewcollection_0_shard2_replica4/data/]
[junit4] 2> 530467 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica2] Schema name=minimal
[junit4] 2> 530468 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530468 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica2' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530469 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530469 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node2/awhollynewcollection_0_shard1_replica2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node2/./awhollynewcollection_0_shard1_replica2/data/]
[junit4] 2> 530485 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530485 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530486 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530486 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530487 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.s.SolrIndexSearcher Opening [Searcher@7de6cf80[awhollynewcollection_0_shard2_replica4] main]
[junit4] 2> 530487 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530487 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530488 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530488 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530488 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2160b8ea[awhollynewcollection_0_shard1_replica4] main]
[junit4] 2> 530490 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530490 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912183513088
[junit4] 2> 530491 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912183513088
[junit4] 2> 530492 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530493 INFO (searcherExecutor-2655-thread-1-processing-x:awhollynewcollection_0_shard1_replica4 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE n:127.0.0.1:35351_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica4] Registered new searcher Searcher@2160b8ea[awhollynewcollection_0_shard1_replica4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530494 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530494 INFO (searcherExecutor-2656-thread-1-processing-x:awhollynewcollection_0_shard2_replica4 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE n:127.0.0.1:35351_solr s:shard2 c:awhollynewcollection_0) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.SolrCore [awhollynewcollection_0_shard2_replica4] Registered new searcher Searcher@7de6cf80[awhollynewcollection_0_shard2_replica4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530504 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530506 INFO (parallelCoreAdminExecutor-2647-thread-2-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024793455 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica4] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=4 found=1 timeoutin=9999ms
[junit4] 2> 530506 INFO (parallelCoreAdminExecutor-2647-thread-1-processing-n:127.0.0.1:35351_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024887581 CREATE) [n:127.0.0.1:35351_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica4] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=4 found=1 timeoutin=9999ms
[junit4] 2> 530508 INFO (qtp556525585-7132) [n:127.0.0.1:45469_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588
[junit4] 2> 530508 INFO (qtp556525585-7132) [n:127.0.0.1:45469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 530508 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica3] Schema name=minimal
[junit4] 2> 530510 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica2] Schema name=minimal
[junit4] 2> 530510 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530510 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530510 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica3' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530511 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530511 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node1/awhollynewcollection_0_shard1_replica3], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node1/./awhollynewcollection_0_shard1_replica3/data/]
[junit4] 2> 530512 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530513 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica2' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530513 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530513 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node2/awhollynewcollection_0_shard2_replica2], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node2/./awhollynewcollection_0_shard2_replica2/data/]
[junit4] 2> 530515 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 530522 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530522 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530523 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530523 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530524 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@4cbc1767[awhollynewcollection_0_shard1_replica2] main]
[junit4] 2> 530525 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica1] Schema name=minimal
[junit4] 2> 530525 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica3] Schema name=minimal
[junit4] 2> 530527 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530560 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica1' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard2_replica3' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node3/awhollynewcollection_0_shard2_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node3/./awhollynewcollection_0_shard2_replica1/data/]
[junit4] 2> 530561 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node1/awhollynewcollection_0_shard2_replica3], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node1/./awhollynewcollection_0_shard2_replica3/data/]
[junit4] 2> 530562 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530562 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530563 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530563 INFO (searcherExecutor-2657-thread-1-processing-x:awhollynewcollection_0_shard1_replica2 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE n:127.0.0.1:45469_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica2] Registered new searcher Searcher@4cbc1767[awhollynewcollection_0_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530563 INFO (parallelCoreAdminExecutor-2649-thread-1-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024280588 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912259010560
[junit4] 2> 530563 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica1] Schema name=minimal
[junit4] 2> 530565 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 530565 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'awhollynewcollection_0_shard1_replica1' using configuration from collection awhollynewcollection_0, trusted=true
[junit4] 2> 530565 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 530565 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node3/awhollynewcollection_0_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001/tempDir-001/node3/./awhollynewcollection_0_shard1_replica1/data/]
[junit4] 2> 530575 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530575 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530575 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530575 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530576 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530576 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530576 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530576 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530577 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.s.SolrIndexSearcher Opening [Searcher@26d19f09[awhollynewcollection_0_shard1_replica3] main]
[junit4] 2> 530577 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@8e1c793[awhollynewcollection_0_shard2_replica2] main]
[junit4] 2> 530577 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2649-thread-2-processing-n:127.0.0.1:45469_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912274739200
[junit4] 2> 530578 INFO (searcherExecutor-2667-thread-1-processing-x:awhollynewcollection_0_shard2_replica2 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024074223 CREATE n:127.0.0.1:45469_solr s:shard2 c:awhollynewcollection_0) [n:127.0.0.1:45469_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica2] o.a.s.c.SolrCore [awhollynewcollection_0_shard2_replica2] Registered new searcher Searcher@8e1c793[awhollynewcollection_0_shard2_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530578 INFO (parallelCoreAdminExecutor-2650-thread-2-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912274739200
[junit4] 2> 530578 INFO (searcherExecutor-2666-thread-1-processing-x:awhollynewcollection_0_shard1_replica3 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024962152 CREATE n:127.0.0.1:45211_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica3] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica3] Registered new searcher Searcher@26d19f09[awhollynewcollection_0_shard1_replica3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530592 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530592 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530592 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530592 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.s.SolrIndexSearcher Opening [Searcher@4fec948f[awhollynewcollection_0_shard2_replica3] main]
[junit4] 2> 530593 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17735232[awhollynewcollection_0_shard2_replica1] main]
[junit4] 2> 530594 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2650-thread-1-processing-n:127.0.0.1:45211_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912292564992
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530595 INFO (parallelCoreAdminExecutor-2653-thread-2-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912292564992
[junit4] 2> 530596 INFO (searcherExecutor-2673-thread-1-processing-x:awhollynewcollection_0_shard2_replica3 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025133625 CREATE n:127.0.0.1:45211_solr s:shard2 c:awhollynewcollection_0) [n:127.0.0.1:45211_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica3] o.a.s.c.SolrCore [awhollynewcollection_0_shard2_replica3] Registered new searcher Searcher@4fec948f[awhollynewcollection_0_shard2_replica3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530598 INFO (searcherExecutor-2672-thread-1-processing-x:awhollynewcollection_0_shard2_replica1 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021025070390 CREATE n:127.0.0.1:41407_solr s:shard2 c:awhollynewcollection_0) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard2 x:awhollynewcollection_0_shard2_replica1] o.a.s.c.SolrCore [awhollynewcollection_0_shard2_replica1] Registered new searcher Searcher@17735232[awhollynewcollection_0_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530599 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 530599 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 530600 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 530600 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 530600 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e3cd487[awhollynewcollection_0_shard1_replica1] main]
[junit4] 2> 530600 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 530601 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 530601 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 530601 INFO (searcherExecutor-2674-thread-1-processing-x:awhollynewcollection_0_shard1_replica1 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE n:127.0.0.1:41407_solr s:shard1 c:awhollynewcollection_0) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.c.SolrCore [awhollynewcollection_0_shard1_replica1] Registered new searcher Searcher@2e3cd487[awhollynewcollection_0_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 530601 INFO (parallelCoreAdminExecutor-2653-thread-1-processing-n:127.0.0.1:41407_solr 159b23e3-628f-4fb7-8ea7-1007ef05a6d82678021024203875 CREATE) [n:127.0.0.1:41407_solr c:awhollynewcollection_0 s:shard1 x:awhollynewcollection_0_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1576723912298856448
[junit4] 2> 530667 INFO (zkCallback-919-thread-4
[...truncated too long message...]
he.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:748)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:729)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:305)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 620430 INFO (qtp556525585-7117) [n:127.0.0.1:45469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:35351_solr&onlyIfLeaderActive=true&core=addReplicaColl_shard1_replica1&coreNodeName=core_node7&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=400 QTime=8003
[junit4] 2> 621113 WARN (zkCallback-909-thread-1-processing-n:127.0.0.1:35351_solr) [n:127.0.0.1:35351_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 621113 INFO (jetty-closer-890-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@462c3c19{/solr,null,UNAVAILABLE}
[junit4] 2> 621114 WARN (zkCallback-910-thread-2-processing-n:127.0.0.1:45211_solr) [n:127.0.0.1:45211_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 621115 INFO (jetty-closer-890-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@612bb9a1{/solr,null,UNAVAILABLE}
[junit4] 2> 621127 WARN (zkCallback-906-thread-1-processing-n:127.0.0.1:45469_solr) [n:127.0.0.1:45469_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 621128 INFO (jetty-closer-890-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60db9950{/solr,null,UNAVAILABLE}
[junit4] 2> 621128 ERROR (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 621128 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43593 43593
[junit4] 2> 621134 INFO (Thread-3179) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43593 43593
[junit4] 2> 621135 WARN (Thread-3179) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 23 /solr/configs/conf
[junit4] 2> 5 /solr/configs/conf2
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 230 /solr/clusterstate.json
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 5 /solr/collections/awhollynewcollection_0/state.json
[junit4] 2> 4 /solr/collections/nodes_used_collection/state.json
[junit4] 2> 4 /solr/collections/addReplicaColl/state.json
[junit4] 2> 4 /solr/collections/reloaded_collection/state.json
[junit4] 2> 3 /solr/overseer_elect/election/98545244342714376-127.0.0.1:45469_solr-n_0000000000
[junit4] 2> 3 /solr/collections/acollectionafterbaddelete/state.json
[junit4] 2> 3 /solr/collections/awhollynewcollection_0/leader_elect/shard2/election/98545244342714378-core_node2-n_0000000000
[junit4] 2> 2 /solr/collections/onlyinzk/state.json
[junit4] 2> 2 /solr/collections/halfdeletedcollection/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 366 /solr/collections
[junit4] 2> 25 /solr/live_nodes
[junit4] 2>
[junit4] 2> 621135 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[9DD4B01A261452B0]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_9DD4B01A261452B0-001
[junit4] 2> Aug 25, 2017 5:13:09 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(Lucene62): {_version_=PostingsFormat(name=Memory doPackFST= false), id=FST50}, docValues:{}, maxPointsInLeafNode=740, maxMBSortInHeap=7.688439842231739, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=sr-Latn-ME, timezone=Africa/Porto-Novo
[junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=8,threads=1,free=208499320,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestCollapseQParserPlugin, JsonLoaderTest, UUIDUpdateProcessorFallbackTest, TestTrie, SolrCoreMetricManagerTest, TestQueryUtils, DocumentBuilderTest, CursorMarkTest, HdfsWriteToMultipleCollectionsTest, TestRestManager, TestLMDirichletSimilarityFactory, TestCollectionAPIs, TestTestInjection, TestShardHandlerFactory, TestSolrCoreSnapshots, TestDistribIDF, TestHashPartitioner, CheckHdfsIndexTest, TestCollationFieldDocValues, SolrJmxReporterCloudTest, TestFastOutputStream, TestMaxScoreQueryParser, PreAnalyzedFieldManagedSchemaCloudTest, TestTrieFacet, TestFiltering, MultiTermTest, DistributedFacetPivotLargeTest, TestCSVResponseWriter, TestLuceneMatchVersion, TestCustomDocTransformer, TestRuleBasedAuthorizationPlugin, TestCustomSort, SolrCloudExampleTest, TestTrackingShardHandlerFactory, TestDistributedGrouping, ClusterStateUpdateTest, ZkControllerTest, RankQueryTest, TestQuerySenderListener, BlockCacheTest, CdcrRequestHandlerTest, ConnectionManagerTest, DirectSolrSpellCheckerTest, WordBreakSolrSpellCheckerTest, TestSolrQueryParserDefaultOperatorResource, TestStressCloudBlindAtomicUpdates, ConnectionReuseTest, EchoParamsTest, TestRandomRequestDistribution, RestartWhileUpdatingTest, TestGraphTermsQParserPlugin, TestSolrCloudWithDelegationTokens, TestDFISimilarityFactory, OpenCloseCoreStressTest, MetricsHandlerTest, CSVRequestHandlerTest, TestNoOpRegenerator, TestPartialUpdateDeduplication, CleanupOldIndexTest, SmileWriterTest, SolrIndexSplitterTest, TestSchemaManager, TestChildDocTransformer, TestSystemCollAutoCreate, TestPayloadScoreQParserPlugin, TestCoreContainer, AssignTest, TestFieldCollectionResource, TestSolrFieldCacheMBean, TestConfigReload, TestSchemaSimilarityResource, TestSuggestSpellingConverter, SuggesterFSTTest, DistanceFunctionTest, TestSurroundQueryParser, TestOrdValues, TestQueryTypes, TestFileDictionaryLookup, SpatialFilterTest, ConvertedLegacyTest, DistributedMLTComponentTest, BlockJoinFacetDistribTest, PeerSyncWithIndexFingerprintCachingTest, SpatialHeatmapFacetsTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [201/713 (1!)] on J1 in 93.49s, 20 tests, 1 failure <<< FAILURES!
[...truncated 45139 lines...]