Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2446/

3 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.analytics.facet.ValueFacetTest

Error Message:
Could not load collection from ZK: collection1

Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: 
collection1
        at __randomizedtesting.SeedInfo.seed([5BF579E5AE2FF658]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1236)
        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:674)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
        at 
org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:63)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /collections/collection1/state.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1248)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1234)
        ... 29 more


FAILED:  
junit.framework.TestSuite.org.apache.solr.analytics.legacy.facet.LegacyFieldFacetExtrasCloudTest

Error Message:
Could not load collection from ZK: collection1

Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: 
collection1
        at __randomizedtesting.SeedInfo.seed([5BF579E5AE2FF658]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1236)
        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:674)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
        at 
org.apache.solr.analytics.legacy.LegacyAbstractAnalyticsCloudTest.setupCollection(LegacyAbstractAnalyticsCloudTest.java:51)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /collections/collection1/state.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340)
        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1248)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1234)
        ... 29 more


FAILED:  
org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore

Error Message:
Error from server at https://127.0.0.1:41988/solr: KeeperErrorCode = Session 
expired for /configs/conf

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at https://127.0.0.1:41988/solr: KeeperErrorCode = Session expired 
for /configs/conf
        at 
__randomizedtesting.SeedInfo.seed([C617D3E20919B295:2AD17E56F2850F71]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1105)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:885)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:818)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
        at 
org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore(CollectionsAPIDistributedZkTest.java:307)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 1891 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/build/core/test/temp/junit4-J0-20180322_090744_2874234382568218606782.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] codec: Asserting, pf: BlockTreeOrds, dvf: Lucene70
   [junit4] <<< JVM J0: EOF ----

[...truncated 10852 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest
   [junit4]   2> 1139233 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/init-core-data-001
   [junit4]   2> 1139234 WARN  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 1139234 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1139235 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1139237 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/tempDir-001
   [junit4]   2> 1139237 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1139237 INFO  (Thread-867) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1139237 INFO  (Thread-867) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1139254 ERROR (Thread-867) [    ] 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> 1139337 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:35588
   [junit4]   2> 1139359 INFO  (zkConnectionManagerCallback-1053-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139381 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1139382 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1139383 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1139396 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1139413 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1139413 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1139413 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1139413 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b3c4ca9{/solr,null,AVAILABLE}
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1c0e541b{SSL,[ssl, 
http/1.1]}{127.0.0.1:41988}
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.e.j.s.Server Started @1148605ms
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41988}
   [junit4]   2> 1139415 ERROR (jetty-launcher-1050-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1139415 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-22T10:59:56.407Z
   [junit4]   2> 1139425 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1139425 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1139425 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1139425 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@15ac941f{/solr,null,AVAILABLE}
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@59dc64c0{SSL,[ssl, 
http/1.1]}{127.0.0.1:40625}
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.e.j.s.Server Started @1148616ms
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40625}
   [junit4]   2> 1139426 ERROR (jetty-launcher-1050-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1139426 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-22T10:59:56.418Z
   [junit4]   2> 1139441 INFO  (zkConnectionManagerCallback-1055-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139442 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1139458 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1139458 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1139499 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1139499 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1139499 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1139499 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@12824635{/solr,null,AVAILABLE}
   [junit4]   2> 1139526 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@40118d21{SSL,[ssl, 
http/1.1]}{127.0.0.1:45978}
   [junit4]   2> 1139526 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.e.j.s.Server Started @1148716ms
   [junit4]   2> 1139526 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45978}
   [junit4]   2> 1139527 ERROR (jetty-launcher-1050-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1139527 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1139527 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1139527 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1139527 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-22T10:59:56.519Z
   [junit4]   2> 1139530 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1139530 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1139530 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 1139531 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6dd1d8bf{/solr,null,AVAILABLE}
   [junit4]   2> 1139531 INFO  (jetty-launcher-1050-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35588/solr
   [junit4]   2> 1139531 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@57c47fbb{SSL,[ssl, 
http/1.1]}{127.0.0.1:40298}
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.e.j.s.Server Started @1148762ms
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40298}
   [junit4]   2> 1139572 ERROR (jetty-launcher-1050-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1139572 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-22T10:59:56.564Z
   [junit4]   2> 1139574 INFO  (zkConnectionManagerCallback-1057-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139627 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1139628 INFO  (zkConnectionManagerCallback-1060-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139628 INFO  (zkConnectionManagerCallback-1063-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139630 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1139630 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1139653 INFO  (zkConnectionManagerCallback-1065-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139653 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1139656 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1139656 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1139658 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1139675 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x100f1a0092f0004, likely client has closed socket
   [junit4]   2> 1139677 INFO  (jetty-launcher-1050-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35588/solr
   [junit4]   2> 1139679 INFO  
(zkConnectionManagerCallback-1067-thread-1-processing-n:127.0.0.1:40625_solr) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139679 INFO  (jetty-launcher-1050-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35588/solr
   [junit4]   2> 1139692 INFO  (zkConnectionManagerCallback-1071-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139693 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1139693 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1139713 INFO  (zkConnectionManagerCallback-1075-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139715 INFO  
(zkConnectionManagerCallback-1077-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139715 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x100f1a0092f0008, likely client has closed socket
   [junit4]   2> 1139716 INFO  (jetty-launcher-1050-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35588/solr
   [junit4]   2> 1139745 INFO  
(zkConnectionManagerCallback-1081-thread-1-processing-n:127.0.0.1:45978_solr) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139746 INFO  (zkConnectionManagerCallback-1084-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139798 INFO  
(zkConnectionManagerCallback-1086-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139842 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1139850 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1139855 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:41988_solr
   [junit4]   2> 1139856 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer Overseer 
(id=72323263689064457-127.0.0.1:41988_solr-n_0000000000) starting
   [junit4]   2> 1139856 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40298_solr
   [junit4]   2> 1139871 INFO  
(zkCallback-1085-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1139872 INFO  
(zkCallback-1076-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1139943 INFO  
(zkConnectionManagerCallback-1093-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1139969 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1139971 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:35588/solr ready
   [junit4]   2> 1140006 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41988_solr
   [junit4]   2> 1140029 INFO  
(zkCallback-1076-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1140078 INFO  
(zkCallback-1085-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1140079 INFO  
(zkCallback-1092-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1140478 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1140552 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1140574 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1140575 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40625_solr
   [junit4]   2> 1140589 INFO  
(zkCallback-1085-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1140601 INFO  
(zkCallback-1092-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1140674 INFO  
(zkCallback-1080-thread-1-processing-n:127.0.0.1:45978_solr) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1140691 INFO  
(zkCallback-1076-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1140752 INFO  
(zkCallback-1066-thread-1-processing-n:127.0.0.1:40625_solr) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1140772 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1140774 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45978_solr
   [junit4]   2> 1140775 INFO  
(zkCallback-1092-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1140775 INFO  
(zkCallback-1085-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1140796 INFO  
(zkCallback-1076-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1140820 INFO  
(zkCallback-1080-thread-1-processing-n:127.0.0.1:45978_solr) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1140833 INFO  
(zkCallback-1066-thread-1-processing-n:127.0.0.1:40625_solr) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1141025 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141061 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141061 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141062 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/tempDir-001/node1
   [junit4]   2> 1141366 INFO  
(zkConnectionManagerCallback-1097-thread-1-processing-n:127.0.0.1:40298_solr) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141431 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 1141431 INFO  (jetty-launcher-1050-thread-1) 
[n:127.0.0.1:40298_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:35588/solr ready
   [junit4]   2> 1141462 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141554 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141554 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141555 INFO  (jetty-launcher-1050-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/tempDir-001/node3
   [junit4]   2> 1141725 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141762 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141762 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1141780 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/tempDir-001/node4
   [junit4]   2> 1141881 INFO  
(zkConnectionManagerCallback-1101-thread-1-processing-n:127.0.0.1:45978_solr) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1141955 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 1141956 INFO  (jetty-launcher-1050-thread-4) 
[n:127.0.0.1:45978_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:35588/solr ready
   [junit4]   2> 1142189 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1142225 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1142225 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3a2a62eb
   [junit4]   2> 1142239 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001/tempDir-001/node2
   [junit4]   2> 1142254 INFO  
(zkConnectionManagerCallback-1105-thread-1-processing-n:127.0.0.1:40625_solr) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1142255 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 1142256 INFO  (jetty-launcher-1050-thread-2) 
[n:127.0.0.1:40625_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:35588/solr ready
   [junit4]   2> 1142430 INFO  (zkConnectionManagerCallback-1107-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1142453 INFO  (zkConnectionManagerCallback-1111-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1142462 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1142463 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[C617D3E20919B295]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35588/solr ready
   [junit4]   2> 1142613 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[C617D3E20919B295])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testNoConfigSetExist
   [junit4]   2> 1142614 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[C617D3E20919B295])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1142731 INFO  (qtp437214610-4215) [n:127.0.0.1:41988_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf123&name=noconfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1142742 INFO  
(OverseerThreadFactory-1531-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
noconfig
   [junit4]   2> 1142750 ERROR 
(OverseerThreadFactory-1531-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Collection: noconfig operation: create 
failed:org.apache.solr.common.SolrException: Can not find the specified config 
set: conf123
   [junit4]   2>        at 
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.validateConfigOrThrowSolrException(OverseerCollectionMessageHandler.java:739)
   [junit4]   2>        at 
org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:114)
   [junit4]   2>        at 
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:252)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [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> 1142753 INFO  (qtp437214610-4215) [n:127.0.0.1:41988_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf123&name=noconfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
 status=400 QTime=22
   [junit4]   2> 1143787 INFO  (qtp437214610-4213) [n:127.0.0.1:41988_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1143787 INFO  (qtp437214610-4213) [n:127.0.0.1:41988_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1143787 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoConfigSetExist-seed#[C617D3E20919B295])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testNoConfigSetExist
   [junit4]   2> 1143926 INFO  
(TEST-CollectionsAPIDistributedZkTest.addReplicaTest-seed#[C617D3E20919B295]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Starting addReplicaTest
   [junit4]   2> 1143937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterprops.json
   [junit4]   2> 1143937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1143937 INFO  
(TEST-CollectionsAPIDistributedZkTest.addReplicaTest-seed#[C617D3E20919B295]) [ 
   ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1143937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1143951 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1143951 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1143952 INFO  (qtp437214610-4215) [n:127.0.0.1:41988_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&maxShardsPerNode=4&name=addReplicaColl&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1143952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1143953 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1143953 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000002
   [junit4]   2> 1143953 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.ZkDistributedQueue Found child node with 
improper name: qnr-0000000002
   [junit4]   2> 1143954 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1143970 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1143970 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.ZkDistributedQueue Found child node with 
improper name: qnr-0000000002
   [junit4]   2> 1143971 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskQueue Returning topN 
elements: [/overseer/collection-queue-work/qn-0000000002, ]
   [junit4]   2> 1143971 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from 
work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ed1]]
   [junit4]   2> 1143971 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Marked task 
[/overseer/collection-queue-work/qn-0000000002] as running
   [junit4]   2> 1143971 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Overseer Collection 
Message Handler: Get the message 
id:/overseer/collection-queue-work/qn-0000000002 message:{
   [junit4]   2>   "name":"addReplicaColl",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "collection.configName":"conf",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"}
   [junit4]   2> 1143972 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Cleaning up 
work-queue. #Running tasks: 1
   [junit4]   2> 1143972 INFO  
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_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> 1143972 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1143973 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1143973 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1143973 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1143973 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskQueue Peeking for top 99 
elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[]
   [junit4]   2> 1143973 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Runner processing 
/overseer/collection-queue-work/qn-0000000002
   [junit4]   2> 1143973 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
OverseerCollectionMessageHandler.processMessage : create , {
   [junit4]   2>   "name":"addReplicaColl",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "collection.configName":"conf",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"}
   [junit4]   2> 1143974 INFO  
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
addReplicaColl
   [junit4]   2> 1143991 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.Assign Identify nodes using default
   [junit4]   2> 1143991 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
creating collections conf node /collections/addReplicaColl 
   [junit4]   2> 1143991 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143991 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143991 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143991 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143992 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143992 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143992 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143992 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143992 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1143993 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1143993 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1143993 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1143993 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Check for 
collection zkNode:addReplicaColl
   [junit4]   2> 1143993 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode 
exists
   [junit4]   2> 1143994 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1143994 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144007 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144007 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144007 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144007 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144007 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144035 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1144035 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144036 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144037 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "name":"addReplicaColl",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "collection.configName":"conf",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1144037 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ClusterStateMutator building a new 
cName: addReplicaColl
   [junit4]   2> 1144038 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/addReplicaColl/state.json
   [junit4]   2> 1144038 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144039 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144165 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores 
for new collection addReplicaColl, shardNames [shard1, shard2] , message : {
   [junit4]   2>   "name":"addReplicaColl",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "collection.configName":"conf",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"}
   [junit4]   2> 1144234 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Creating core 
addReplicaColl_shard1_replica_n1 as part of shard shard1 of collection 
addReplicaColl on 127.0.0.1:40625_solr
   [junit4]   2> 1144236 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144237 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Creating core 
addReplicaColl_shard1_replica_n2 as part of shard shard1 of collection 
addReplicaColl on 127.0.0.1:45978_solr
   [junit4]   2> 1144250 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144250 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144251 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1144251 INFO  
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1144252 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Creating core 
addReplicaColl_shard2_replica_n3 as part of shard shard2 of collection 
addReplicaColl on 127.0.0.1:41988_solr
   [junit4]   2> 1144252 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1144252 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node4":{
   [junit4]   2>       "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>       "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144252 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144253 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 2, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1144253 INFO  
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1144253 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144269 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node4":{
   [junit4]   2>       "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>       "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144269 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node4":{
   [junit4]   2>       "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>       "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>       "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144269 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144270 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 3, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1144286 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.CreateCollectionCmd Creating core 
addReplicaColl_shard2_replica_n6 as part of shard shard2 of collection 
addReplicaColl on 127.0.0.1:40298_solr
   [junit4]   2> 1144287 INFO  
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1144288 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144288 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Expecting [addReplicaColl_shard1_replica_n1, addReplicaColl_shard1_replica_n2, 
addReplicaColl_shard2_replica_n3, addReplicaColl_shard2_replica_n6] cores but 
found {}
   [junit4]   2> 1144290 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1144290 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator New Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node7":{
   [junit4]   2>       "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>       "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144290 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144303 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 4, 
message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40298/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1144303 INFO  
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40298/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1144305 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator Old Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node7":{
   [junit4]   2>       "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>       "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144306 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.SliceMutator New Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node7":{
   [junit4]   2>       "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>       "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node8":{
   [junit4]   2>       "core":"addReplicaColl_shard2_replica_n6",
   [junit4]   2>       "base_url":"https://127.0.0.1:40298/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1144391 DEBUG (ScheduledTrigger-1529-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1144408 DEBUG 
(OverseerThreadFactory-1531-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Expecting [addReplicaColl_shard1_replica_n1, addReplicaColl_shard1_replica_n2, 
addReplicaColl_shard2_replica_n3, addReplicaColl_shard2_replica_n6] cores but 
found {}
   [junit4]   2> 1144437 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/addReplicaColl/state.json version: 0
   [junit4]   2> 1144438 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1144438 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1144751 INFO  (qtp437214610-4210) [n:127.0.0.1:41988_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 7s
   [junit4]   2> 1144865 INFO  (qtp2146921494-4226) [n:127.0.0.1:40625_solr    
] o.a.s.u.TestInjection Inject random core creation delay of 7s
   [junit4]   2> 1144916 INFO  (qtp718099165-4246) [n:127.0.0.1:40298_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 7s
   [junit4]   2> 1144917 INFO  (qtp44565038-4238) [n:127.0.0.1:45978_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 7s
   [junit4]   2> 1145391 DEBUG (ScheduledTrigger-1529-thread-1) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1145973 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from 
work-queue : [[]]
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Cleaning up 
work-queue. #Running tasks: 1
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1145989 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskQueue Peeking for top 99 
elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[]
   [junit4]   2> 1146391 DEBUG (ScheduledTrigger-1529-thread-2) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1147398 DEBUG (ScheduledTrigger-1529-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1147994 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from 
work-queue : [[]]
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Cleaning up 
work-queue. #Running tasks: 1
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1147995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskQueue Peeking for top 99 
elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[]
   [junit4]   2> 1148404 DEBUG (ScheduledTrigger-1529-thread-4) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1149405 DEBUG (ScheduledTrigger-1529-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1149995 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from 
work-queue : [[]]
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor Cleaning up 
work-queue. #Running tasks: 1
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000002]
   [junit4]   2> 1149996 DEBUG 
(OverseerCollectionConfigSetProcessor-72323263689064457-127.0.0.1:41988_solr-n_0000000000)
 [n:127.0.0.1:41988_solr    ] o.a.s.c.OverseerTaskQueue Peeking for top 99 
elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[]
   [junit4]   2> 1150405 DEBUG (ScheduledTrigger-1529-thread-4) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1151405 DEBUG (ScheduledTrigger-1529-thread-3) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 4
   [junit4]   2> 1151752 INFO  (qtp437214610-4210) [n:127.0.0.1:41988_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica_n3&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1151752 INFO  (qtp437214610-4210) [n:127.0.0.1:41988_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1151754 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151755 DEBUG (qtp437214610-4210) [n:127.0.0.1:41988_solr 
c:addReplicaColl s:shard2 r:core_node7 x:addReplicaColl_shard2_replica_n3] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1151755 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1151756 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1151756 DEBUG (qtp437214610-4210) [n:127.0.0.1:41988_solr 
c:addReplicaColl s:shard2 r:core_node7 x:addReplicaColl_shard2_replica_n3] 
o.a.s.c.ZkController Registering watch for collection addReplicaColl
   [junit4]   2> 1151756 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151756 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>   "core_node_name":"core_node7",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:41988_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1151756 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
message={
   [junit4]   2>   "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>   "core_node_name":"core_node7",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:41988_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1151756 DEBUG (qtp437214610-4210) [n:127.0.0.1:41988_solr 
c:addReplicaColl s:shard2 r:core_node7 x:addReplicaColl_shard2_replica_n3] 
o.a.s.c.ZkController waiting to find shard id in clusterstate for 
addReplicaColl_shard2_replica_n3
   [junit4]   2> 1151756 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node7:{"core":"addReplicaColl_shard2_replica_n3","base_url":"https://127.0.0.1:41988/solr","node_name":"127.0.0.1:41988_solr","state":"down","type":"NRT"}
   [junit4]   2> 1151756 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(addReplicaColl//collections/addReplicaColl/state.json/1)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{
   [junit4]   2>     "shard1":{
   [junit4]   2>       "range":"80000000-ffffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>           "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>           "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}},
   [junit4]   2>     "shard2":{
   [junit4]   2>       "range":"0-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node7":{
   [junit4]   2>           "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>           "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:41988_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node8":{
   [junit4]   2>           "core":"addReplicaColl_shard2_replica_n6",
   [junit4]   2>           "base_url":"https://127.0.0.1:40298/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 1151757 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/addReplicaColl/state.json version: 1
   [junit4]   2> 1151757 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151757 INFO  
(zkCallback-1076-thread-1-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1151757 INFO  
(zkCallback-1076-thread-2-processing-n:127.0.0.1:41988_solr) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/addReplicaColl/state.json] for collection [addReplicaColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1151757 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1151757 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151757 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151758 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1151874 INFO  (qtp2146921494-4226) [n:127.0.0.1:40625_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica_n1&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1151875 INFO  (qtp2146921494-4226) [n:127.0.0.1:40625_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1151876 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151877 DEBUG (qtp2146921494-4226) [n:127.0.0.1:40625_solr 
c:addReplicaColl s:shard1 r:core_node4 x:addReplicaColl_shard1_replica_n1] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1151877 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1151878 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1151878 DEBUG (qtp2146921494-4226) [n:127.0.0.1:40625_solr 
c:addReplicaColl s:shard1 r:core_node4 x:addReplicaColl_shard1_replica_n1] 
o.a.s.c.ZkController Registering watch for collection addReplicaColl
   [junit4]   2> 1151878 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151878 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:40625_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1151878 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 
message={
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:40625_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1151878 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Will update state for 
replica: 
core_node4:{"core":"addReplicaColl_shard1_replica_n1","base_url":"https://127.0.0.1:40625/solr","node_name":"127.0.0.1:40625_solr","state":"down","type":"NRT"}
   [junit4]   2> 1151878 DEBUG (qtp2146921494-4226) [n:127.0.0.1:40625_solr 
c:addReplicaColl s:shard1 r:core_node4 x:addReplicaColl_shard1_replica_n1] 
o.a.s.c.ZkController waiting to find shard id in clusterstate for 
addReplicaColl_shard1_replica_n1
   [junit4]   2> 1151878 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(addReplicaColl//collections/addReplicaColl/state.json/2)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"2",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"4",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"2",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{
   [junit4]   2>     "shard1":{
   [junit4]   2>       "range":"80000000-ffffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node4":{
   [junit4]   2>           "core":"addReplicaColl_shard1_replica_n1",
   [junit4]   2>           "base_url":"https://127.0.0.1:40625/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:40625_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>           "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}},
   [junit4]   2>     "shard2":{
   [junit4]   2>       "range":"0-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node7":{
   [junit4]   2>           "core":"addReplicaColl_shard2_replica_n3",
   [junit4]   2>           "base_url":"https://127.0.0.1:41988/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:41988_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node8":{
   [junit4]   2>           "core":"addReplicaColl_shard2_replica_n6",
   [junit4]   2>           "base_url":"https://127.0.0.1:40298/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 1151917 INFO  (qtp718099165-4246) [n:127.0.0.1:40298_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=addReplicaColl_shard2_replica_n6&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1151917 INFO  (qtp718099165-4246) [n:127.0.0.1:40298_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1151917 INFO  (qtp44565038-4238) [n:127.0.0.1:45978_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=addReplicaColl_shard1_replica_n2&action=CREATE&numShards=2&collection=addReplicaColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1151917 INFO  (qtp44565038-4238) [n:127.0.0.1:45978_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1151918 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151918 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151920 DEBUG (qtp718099165-4246) [n:127.0.0.1:40298_solr 
c:addReplicaColl s:shard2 r:core_node8 x:addReplicaColl_shard2_replica_n6] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1151920 DEBUG (qtp44565038-4238) [n:127.0.0.1:45978_solr 
c:addReplicaColl s:shard1 r:core_node5 x:addReplicaColl_shard1_replica_n2] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1151921 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1151921 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1151921 DEBUG (qtp44565038-4238) [n:127.0.0.1:45978_solr 
c:addReplicaColl s:shard1 r:core_node5 x:addReplicaColl_shard1_replica_n2] 
o.a.s.c.ZkController Registering watch for collection addReplicaColl
   [junit4]   2> 1151921 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addReplicaColl/state.json
   [junit4]   2> 1151921 DEBUG 
(OverseerStateUpdate-72323263689064457-127.0.0.1:41988_solr-n_0000000000) 
[n:127.0.0.1:41988_solr    ] o.a.s.c.Overseer processMessage: queueSize: 2, 
message = {
   [junit4]   2>   "core":"addReplicaColl_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node5",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"https://127.0.0.1:45978/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:45978_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addReplicaColl",
   [junit4]   2>   "type":"NRT",
   [jun

[...truncated too long message...]

rd1/election/72323263689064460-core_node10-n_0000000002
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        179     /solr/collections
   [junit4]   2>        37      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIDistributedZkTest_C617D3E20919B295-001
   [junit4]   2> Mar 22, 2018 11:03:12 AM 
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(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=622, maxMBSortInHeap=7.301494921944036, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@93fe97b),
 locale=ar-JO, timezone=America/Curacao
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=296006776,total=523239424
   [junit4]   2> NOTE: All tests run in this JVM: 
[PKIAuthenticationIntegrationTest, TestBinaryResponseWriter, UUIDFieldTest, 
TestOverriddenPrefixQueryForCustomFieldType, CdcrReplicationDistributedZkTest, 
TestFastOutputStream, TlogReplayBufferedWhileIndexingTest, 
HLLSerializationTest, TestDocSet, TestQuerySenderNoQuery, TestExpandComponent, 
TestSizeLimitedDistributedMap, MetricsConfigTest, TestSearcherReuse, 
ZkSolrClientTest, StatsComponentTest, ChaosMonkeyNothingIsSafeTest, 
TestInitQParser, DistributedQueryElevationComponentTest, HdfsNNFailoverTest, 
PreAnalyzedUpdateProcessorTest, AtomicUpdateProcessorFactoryTest, 
TestCollationField, CachingDirectoryFactoryTest, ZkStateWriterTest, 
SolrIndexSplitterTest, SolrIndexConfigTest, HttpPartitionOnCommitTest, 
V2ApiIntegrationTest, SpellCheckCollatorWithCollapseTest, TestComponentsName, 
SolrCoreCheckLockOnStartupTest, TestBlobHandler, BlockJoinFacetSimpleTest, 
SolrSlf4jReporterTest, TestImpersonationWithHadoopAuth, TestBulkSchemaAPI, 
OverseerCollectionConfigSetProcessorTest, TestShardHandlerFactory, 
AliasIntegrationTest, TestCloudSearcherWarming, TestDistribIDF, 
TestSolr4Spatial2, ComputePlanActionTest, TestMergePolicyConfig, 
NodeMutatorTest, QueryResultKeyTest, TestIndexSearcher, CursorPagingTest, 
SearchRateTriggerTest, CollectionsAPIDistributedZkTest]
   [junit4] Completed [185/785 (1!)] on J0 in 196.90s, 19 tests, 1 error, 1 
skipped <<< FAILURES!

[...truncated 5295 lines...]
   [junit4] Suite: org.apache.solr.analytics.facet.ValueFacetTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-analytics/test/J2/temp/solr.analytics.facet.ValueFacetTest_5BF579E5AE2FF658-001/init-core-data-001
   [junit4]   2> Mar 22, 2018 12:10:57 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(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1635, maxMBSortInHeap=6.618502752431736, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4c64b1b0),
 locale=uk, timezone=America/Sitka
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=131874056,total=298319872
   [junit4]   2> NOTE: All tests run in this JVM: [TopFunctionTest, 
CastingLongValueTest, LegacyFacetSortingTest, LegacyFunctionTest, 
FillMissingFunctionTest, CastingDoubleValueTest, FloatFieldsTest, 
AndFunctionTest, LegacyNoFacetCloudTest, PowerFunctionTest, 
CastingDateValueStreamTest, CastingAnalyticsValueTest, RoundFunctionTest, 
PivotFacetTest, ValueFacetTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ValueFacetTest 
-Dtests.seed=5BF579E5AE2FF658 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=uk -Dtests.timezone=America/Sitka -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J2 | ValueFacetTest (suite) <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
load collection from ZK: collection1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5BF579E5AE2FF658]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1236)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:674)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
   [junit4]    >        at 
org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:63)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: 
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /collections/collection1/state.json
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
   [junit4]    >        at 
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1248)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1234)
   [junit4]    >        ... 29 more
   [junit4] Completed [66/78 (1!)] on J2 in 27.97s, 0 tests, 1 error <<< 
FAILURES!

[...truncated 7 lines...]
   [junit4] Suite: 
org.apache.solr.analytics.legacy.facet.LegacyFieldFacetExtrasCloudTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-analytics/test/J1/temp/solr.analytics.legacy.facet.LegacyFieldFacetExtrasCloudTest_5BF579E5AE2FF658-001/init-core-data-001
   [junit4]   2> Mar 22, 2018 12:11:31 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=CheapBastard, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@489497e1),
 locale=es-NI, timezone=America/Cordoba
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=169840152,total=308281344
   [junit4]   2> NOTE: All tests run in this JVM: [SubtractFunctionTest, 
CastingLongValueStreamTest, LegacyAbstractAnalyticsFacetTest, 
LegacyExpressionTest, ExistsFunctionTest, CastingDoubleValueStreamTest, 
DoubleFieldsTest, AddFunctionTest, LegacyAbstractAnalyticsTest, OrFunctionTest, 
CastingBooleanValueTest, LegacyRangeFacetTest, ReplaceFunctionTest, 
ExpressionFactoryTest, NoFacetTest, QueryFacetTest, AbstractAnalyticsFieldTest, 
BooleanFieldsTest, IntFieldsTest, LongFieldsTest, StringFieldsTest, 
BottomFunctionTest, DateMathFunctionTest, DateParseFunctionTest, 
FloorFunctionTest, GTEFunctionTest, LTEFunctionTest, LogFunctionTest, 
LegacyFieldFacetExtrasCloudTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=LegacyFieldFacetExtrasCloudTest -Dtests.seed=5BF579E5AE2FF658 
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-NI 
-Dtests.timezone=America/Cordoba -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | LegacyFieldFacetExtrasCloudTest (suite) <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
load collection from ZK: collection1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([5BF579E5AE2FF658]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1236)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:674)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
   [junit4]    >        at 
org.apache.solr.analytics.legacy.LegacyAbstractAnalyticsCloudTest.setupCollection(LegacyAbstractAnalyticsCloudTest.java:51)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: 
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /collections/collection1/state.json
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
   [junit4]    >        at 
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1248)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1234)
   [junit4]    >        ... 29 more
   [junit4] Completed [69/78 (2!)] on J1 in 36.17s, 0 tests, 1 error <<< 
FAILURES!

[...truncated 46313 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to