Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/212/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI

Error Message:
Error from server at 
http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1: 
ClusterState says we are the leader 
(http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1), but 
locally we don't think so. Request came from null

Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from 
server at http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1: 
ClusterState says we are the leader 
(http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1), but 
locally we don't think so. Request came from null
        at 
__randomizedtesting.SeedInfo.seed([3D04F12EB853F3B0:7571859ABE60DC25]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:459)
        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)
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at 
http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1: 
ClusterState says we are the leader 
(http://127.0.0.1:47626/solr/awhollynewcollection_0_shard1_replica_n1), but 
locally we don't think so. Request came from null
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
        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.lambda$directUpdate$0(CloudSolrClient.java:516)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        ... 1 more




Build Log:
[...truncated 12511 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/init-core-data-001
   [junit4]   2> 2193765 WARN  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=61 numCloses=61
   [junit4]   2> 2193765 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2193766 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2193766 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2193767 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001
   [junit4]   2> 2193767 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2193767 INFO  (Thread-5004) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2193767 INFO  (Thread-5004) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2193772 ERROR (Thread-5004) [    ] 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> 2193867 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:51472
   [junit4]   2> 2193904 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2193904 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2193905 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2193911 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2193914 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7a43b282{/solr,null,AVAILABLE}
   [junit4]   2> 2193915 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@54f7583{/solr,null,AVAILABLE}
   [junit4]   2> 2193915 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5fae41d3{/solr,null,AVAILABLE}
   [junit4]   2> 2193915 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3d2da857{HTTP/1.1,[http/1.1]}{127.0.0.1:61108}
   [junit4]   2> 2193915 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.e.j.s.Server Started @2196188ms
   [junit4]   2> 2193915 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=61108}
   [junit4]   2> 2193915 ERROR (jetty-launcher-2153-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T23:44:09.902Z
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@bb5a5db{HTTP/1.1,[http/1.1]}{127.0.0.1:47626}
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.e.j.s.Server Started @2196189ms
   [junit4]   2> 2193916 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=47626}
   [junit4]   2> 2193917 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7dd7f6d8{/solr,null,AVAILABLE}
   [junit4]   2> 2193919 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1642906{HTTP/1.1,[http/1.1]}{127.0.0.1:50406}
   [junit4]   2> 2193919 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.e.j.s.Server Started @2196192ms
   [junit4]   2> 2193919 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50406}
   [junit4]   2> 2193920 ERROR (jetty-launcher-2153-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2193920 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2193920 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2193920 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2193920 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T23:44:09.906Z
   [junit4]   2> 2193923 ERROR (jetty-launcher-2153-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2657a41d{HTTP/1.1,[http/1.1]}{127.0.0.1:37164}
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.e.j.s.Server Started @2196196ms
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T23:44:09.909Z
   [junit4]   2> 2193923 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37164}
   [junit4]   2> 2193924 ERROR (jetty-launcher-2153-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2193924 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2193924 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2193924 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2193924 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T23:44:09.910Z
   [junit4]   2> 2193928 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2193931 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2193931 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2193934 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2193942 INFO  (jetty-launcher-2153-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51472/solr
   [junit4]   2> 2193942 INFO  (jetty-launcher-2153-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51472/solr
   [junit4]   2> 2193946 INFO  (jetty-launcher-2153-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51472/solr
   [junit4]   2> 2193951 INFO  (jetty-launcher-2153-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51472/solr
   [junit4]   2> 2194121 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2194127 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:61108_solr
   [junit4]   2> 2194127 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2194130 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.Overseer Overseer 
(id=98739306479878155-127.0.0.1:61108_solr-n_0000000000) starting
   [junit4]   2> 2194130 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37164_solr
   [junit4]   2> 2194135 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2194136 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2194136 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2194138 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2194139 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2194143 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50406_solr
   [junit4]   2> 2194146 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2194149 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2194149 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2194149 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2194149 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2194155 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:47626_solr
   [junit4]   2> 2194173 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2194173 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2194188 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2194192 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194195 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:61108_solr
   [junit4]   2> 2194197 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194197 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194198 INFO  (jetty-launcher-2153-thread-4) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node4
   [junit4]   2> 2194199 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2194211 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2194211 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2194211 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2194211 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2194238 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194244 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194244 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194245 INFO  (jetty-launcher-2153-thread-1) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node1
   [junit4]   2> 2194263 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194270 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194270 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194271 INFO  (jetty-launcher-2153-thread-2) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node2
   [junit4]   2> 2194273 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194277 WARN  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.m.r.j.JmxMetricsReporter Unable to register 
gauge
   [junit4]   2> javax.management.InstanceNotFoundException: 
solr:dom1=jvm,name0=os,name=systemCpuLoad
   [junit4]   2>        at 
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
   [junit4]   2>        at 
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
   [junit4]   2>        at 
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2>        at 
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2>        at 
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
   [junit4]   2>        at 
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
   [junit4]   2>        at 
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
   [junit4]   2>        at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2>        at 
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
   [junit4]   2>        at 
org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109)
   [junit4]   2>        at 
org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
   [junit4]   2>        at 
org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:849)
   [junit4]   2>        at 
org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817)
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.load(CoreContainer.java:544)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
   [junit4]   2>        at 
org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
   [junit4]   2>        at 
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
   [junit4]   2>        at 
org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384)
   [junit4]   2>        at 
org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [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> 2194280 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194280 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2194290 INFO  (jetty-launcher-2153-thread-3) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node3
   [junit4]   2> 2194327 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2194328 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51472/solr ready
   [junit4]   2> 2194576 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[3D04F12EB853F3B0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCoresAreDistributedAcrossNodes
   [junit4]   2> 2194578 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[3D04F12EB853F3B0])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2194591 INFO  (qtp1603776564-16948) [n:127.0.0.1:37164_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&name=nodes_used_collection&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2194594 INFO  
(OverseerThreadFactory-6178-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
nodes_used_collection
   [junit4]   2> 2194715 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"nodes_used_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"nodes_used_collection_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61108/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2194723 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"nodes_used_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"nodes_used_collection_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:37164/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2194730 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"nodes_used_collection",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"nodes_used_collection_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:50406/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2194736 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"nodes_used_collection",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"nodes_used_collection_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:47626/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2194963 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica_n2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2194964 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica_n6&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2194964 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2194964 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica_n4&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2194965 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2194970 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2194971 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2194971 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2195093 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195094 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195094 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195093 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195095 INFO  
(zkCallback-2173-thread-2-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195095 INFO  
(zkCallback-2172-thread-2-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195095 INFO  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2195095 INFO  
(zkCallback-2175-thread-2-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196016 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 2196016 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 2196032 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 2196038 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.s.IndexSchema 
[nodes_used_collection_shard2_replica_n6] Schema name=minimal
   [junit4]   2> 2196038 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.s.IndexSchema 
[nodes_used_collection_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 2196039 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.s.IndexSchema 
[nodes_used_collection_shard2_replica_n4] Schema name=minimal
   [junit4]   2> 2196041 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 2196041 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.c.CoreContainer Creating 
SolrCore 'nodes_used_collection_shard2_replica_n6' using configuration from 
collection nodes_used_collection, trusted=true
   [junit4]   2> 2196042 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 2196042 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 2196042 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.CoreContainer Creating 
SolrCore 'nodes_used_collection_shard2_replica_n4' using configuration from 
collection nodes_used_collection, trusted=true
   [junit4]   2> 2196042 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.nodes_used_collection.shard2.replica_n6' (registry 
'solr.core.nodes_used_collection.shard2.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2196042 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2196042 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.nodes_used_collection.shard2.replica_n4' (registry 
'solr.core.nodes_used_collection.shard2.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2196042 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.c.SolrCore 
[[nodes_used_collection_shard2_replica_n6] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node3/nodes_used_collection_shard2_replica_n6],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node3/nodes_used_collection_shard2_replica_n6/data/]
   [junit4]   2> 2196042 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2196042 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SolrCore 
[[nodes_used_collection_shard2_replica_n4] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node1/nodes_used_collection_shard2_replica_n4],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node1/nodes_used_collection_shard2_replica_n4/data/]
   [junit4]   2> 2196053 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 2196053 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.CoreContainer Creating 
SolrCore 'nodes_used_collection_shard1_replica_n2' using configuration from 
collection nodes_used_collection, trusted=true
   [junit4]   2> 2196054 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.nodes_used_collection.shard1.replica_n2' (registry 
'solr.core.nodes_used_collection.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2196054 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2196054 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SolrCore 
[[nodes_used_collection_shard1_replica_n2] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node4/nodes_used_collection_shard1_replica_n2],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node4/nodes_used_collection_shard1_replica_n2/data/]
   [junit4]   2> 2196056 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.s.IndexSchema 
[nodes_used_collection_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 2196057 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 2196058 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'nodes_used_collection_shard1_replica_n1' using configuration from 
collection nodes_used_collection, trusted=true
   [junit4]   2> 2196058 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.nodes_used_collection.shard1.replica_n1' (registry 
'solr.core.nodes_used_collection.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2196058 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2196058 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[[nodes_used_collection_shard1_replica_n1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node2/nodes_used_collection_shard1_replica_n1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node2/nodes_used_collection_shard1_replica_n1/data/]
   [junit4]   2> 2196102 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2196102 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2196103 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2196103 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2196105 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@216b5f47[nodes_used_collection_shard2_replica_n4] main]
   [junit4]   2> 2196106 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2196106 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2196106 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2196107 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1579828906130669568
   [junit4]   2> 2196107 INFO  
(searcherExecutor-6186-thread-1-processing-n:127.0.0.1:50406_solr 
x:nodes_used_collection_shard2_replica_n4 s:shard2 c:nodes_used_collection 
r:core_node7) [n:127.0.0.1:50406_solr c:nodes_used_collection s:shard2 
r:core_node7 x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SolrCore 
[nodes_used_collection_shard2_replica_n4] Registered new searcher 
Searcher@216b5f47[nodes_used_collection_shard2_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2196107 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2196107 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2196108 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2196109 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2196110 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@ccf9ce6[nodes_used_collection_shard2_replica_n6] main]
   [junit4]   2> 2196111 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2196112 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2196113 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2196113 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext 
Waiting until we see more replicas up for shard shard2: total=2 found=1 
timeoutin=9999ms
   [junit4]   2> 2196114 INFO  
(searcherExecutor-6185-thread-1-processing-n:127.0.0.1:47626_solr 
x:nodes_used_collection_shard2_replica_n6 s:shard2 c:nodes_used_collection 
r:core_node8) [n:127.0.0.1:47626_solr c:nodes_used_collection s:shard2 
r:core_node8 x:nodes_used_collection_shard2_replica_n6] o.a.s.c.SolrCore 
[nodes_used_collection_shard2_replica_n6] Registered new searcher 
Searcher@ccf9ce6[nodes_used_collection_shard2_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2196115 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1579828906139058176
   [junit4]   2> 2196124 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2196124 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2196138 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2196138 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2196139 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1815ad15[nodes_used_collection_shard1_replica_n2] main]
   [junit4]   2> 2196140 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2196141 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2196141 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2196141 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1579828906166321152
   [junit4]   2> 2196142 INFO  
(searcherExecutor-6187-thread-1-processing-n:127.0.0.1:37164_solr 
x:nodes_used_collection_shard1_replica_n2 s:shard1 c:nodes_used_collection 
r:core_node5) [n:127.0.0.1:37164_solr c:nodes_used_collection s:shard1 
r:core_node5 x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SolrCore 
[nodes_used_collection_shard1_replica_n2] Registered new searcher 
Searcher@1815ad15[nodes_used_collection_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2196143 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2196143 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2196144 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2196144 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2196145 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4afcc2e[nodes_used_collection_shard1_replica_n1] main]
   [junit4]   2> 2196147 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2196148 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext 
Waiting until we see more replicas up for shard shard1: total=2 found=1 
timeoutin=9999ms
   [junit4]   2> 2196148 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2196149 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2196149 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1579828906174709760
   [junit4]   2> 2196150 INFO  
(searcherExecutor-6188-thread-1-processing-n:127.0.0.1:61108_solr 
x:nodes_used_collection_shard1_replica_n1 s:shard1 c:nodes_used_collection 
r:core_node3) [n:127.0.0.1:61108_solr c:nodes_used_collection s:shard1 
r:core_node3 x:nodes_used_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[nodes_used_collection_shard1_replica_n1] Registered new searcher 
Searcher@4afcc2e[nodes_used_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2196252 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196252 INFO  
(zkCallback-2175-thread-2-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196252 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196252 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196252 INFO  
(zkCallback-2172-thread-2-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196253 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196253 INFO  
(zkCallback-2173-thread-2-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196252 INFO  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2196615 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 2196615 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 2196615 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SyncStrategy Sync replicas 
to http://127.0.0.1:50406/solr/nodes_used_collection_shard2_replica_n4/
   [junit4]   2> 2196616 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.PeerSync PeerSync: 
core=nodes_used_collection_shard2_replica_n4 url=http://127.0.0.1:50406/solr 
START 
replicas=[http://127.0.0.1:47626/solr/nodes_used_collection_shard2_replica_n6/] 
nUpdates=100
   [junit4]   2> 2196623 INFO  (qtp1348226055-16926) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.c.S.Request 
[nodes_used_collection_shard2_replica_n6]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 2196649 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 2196649 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 2196649 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas 
to http://127.0.0.1:37164/solr/nodes_used_collection_shard1_replica_n2/
   [junit4]   2> 2196650 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: 
core=nodes_used_collection_shard1_replica_n2 url=http://127.0.0.1:37164/solr 
START 
replicas=[http://127.0.0.1:61108/solr/nodes_used_collection_shard1_replica_n1/] 
nUpdates=100
   [junit4]   2> 2196657 INFO  (qtp1281029843-16937) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.c.S.Request 
[nodes_used_collection_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 2196916 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.u.PeerSync PeerSync: 
core=nodes_used_collection_shard2_replica_n4 url=http://127.0.0.1:50406/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2196916 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.SyncStrategy Leader's 
attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 2196916 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext 
We failed sync, but we have no versions - we can't sync in that case - we were 
active before, so become leader anyway
   [junit4]   2> 2196916 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 2196929 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:50406/solr/nodes_used_collection_shard2_replica_n4/ shard2
   [junit4]   2> 2196950 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: 
core=nodes_used_collection_shard1_replica_n2 url=http://127.0.0.1:37164/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2196950 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's 
attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 2196951 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext 
We failed sync, but we have no versions - we can't sync in that case - we were 
active before, so become leader anyway
   [junit4]   2> 2196951 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 2196962 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:37164/solr/nodes_used_collection_shard1_replica_n2/ shard1
   [junit4]   2> 2197076 INFO  
(zkCallback-2175-thread-2-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197076 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197076 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197076 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197076 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197076 INFO  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197077 INFO  
(zkCallback-2172-thread-2-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197077 INFO  
(zkCallback-2173-thread-2-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197082 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 2197084 INFO  (qtp1538750946-16919) [n:127.0.0.1:50406_solr 
c:nodes_used_collection s:shard2 r:core_node7 
x:nodes_used_collection_shard2_replica_n4] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica_n4&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2120
   [junit4]   2> 2197115 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 2197119 INFO  (qtp1603776564-16947) [n:127.0.0.1:37164_solr 
c:nodes_used_collection s:shard1 r:core_node5 
x:nodes_used_collection_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica_n2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2167
   [junit4]   2> 2197124 INFO  (qtp1348226055-16924) [n:127.0.0.1:47626_solr 
c:nodes_used_collection s:shard2 r:core_node8 
x:nodes_used_collection_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica_n6&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2161
   [junit4]   2> 2197159 INFO  (qtp1281029843-16936) [n:127.0.0.1:61108_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2189
   [junit4]   2> 2197167 INFO  (qtp1603776564-16948) [n:127.0.0.1:37164_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 2197270 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197270 INFO  
(zkCallback-2175-thread-2-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197270 INFO  
(zkCallback-2175-thread-1-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197270 INFO  
(zkCallback-2173-thread-1-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197270 INFO  
(zkCallback-2173-thread-2-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197270 INFO  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197271 INFO  
(zkCallback-2172-thread-2-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2197271 INFO  
(zkCallback-2172-thread-1-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2198168 INFO  (qtp1603776564-16948) [n:127.0.0.1:37164_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=nodes_used_collection&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=3578
   [junit4]   2> 2198170 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[3D04F12EB853F3B0])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCoresAreDistributedAcrossNodes
   [junit4]   2> 2198466 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[3D04F12EB853F3B0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreationAndDeletion
   [junit4]   2> 2198468 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreationAndDeletion-seed#[3D04F12EB853F3B0])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2198471 INFO  (qtp1603776564-16950) [n:127.0.0.1:37164_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=nodes_used_collection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 2198475 INFO  
(OverseerThreadFactory-6178-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 2198475 INFO  
(OverseerCollectionConfigSetProcessor-98739306479878155-127.0.0.1:61108_solr-n_0000000000)
 [n:127.0.0.1:61108_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> 2198476 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard1.replica_n2, tag=null
   [junit4]   2> 2198476 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@42e19899: rootName = null, 
domain = solr.core.nodes_used_collection.shard1.replica_n2, service url = null, 
agent id = null] for registry solr.core.nodes_used_collection.shard1.replica_n2 
/ com.codahale.metrics.MetricRegistry@7b63fc00
   [junit4]   2> 2198476 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard2.replica_n4, tag=null
   [junit4]   2> 2198476 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@489e873f: rootName = null, 
domain = solr.core.nodes_used_collection.shard2.replica_n4, service url = null, 
agent id = null] for registry solr.core.nodes_used_collection.shard2.replica_n4 
/ com.codahale.metrics.MetricRegistry@5d59c06b
   [junit4]   2> 2198476 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard1.replica_n1, tag=null
   [junit4]   2> 2198476 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@41f11239: rootName = null, 
domain = solr.core.nodes_used_collection.shard1.replica_n1, service url = null, 
agent id = null] for registry solr.core.nodes_used_collection.shard1.replica_n1 
/ com.codahale.metrics.MetricRegistry@28ff6733
   [junit4]   2> 2198477 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard2.replica_n6, tag=null
   [junit4]   2> 2198477 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c2daedc: rootName = null, 
domain = solr.core.nodes_used_collection.shard2.replica_n6, service url = null, 
agent id = null] for registry solr.core.nodes_used_collection.shard2.replica_n6 
/ com.codahale.metrics.MetricRegistry@38c81a96
   [junit4]   2> 2198608 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.c.SolrCore [nodes_used_collection_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@fa0a544
   [junit4]   2> 2198608 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard1.replica_n1, tag=262186308
   [junit4]   2> 2198608 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.nodes_used_collection.shard1.leader, tag=262186308
   [junit4]   2> 2198609 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.c.SolrCore [nodes_used_collection_shard2_replica_n6]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5a822192
   [junit4]   2> 2198609 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.c.SolrCore [nodes_used_collection_shard2_replica_n4]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@745a7c48
   [junit4]   2> 2198609 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.c.SolrCore [nodes_used_collection_shard1_replica_n2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4e81fe91
   [junit4]   2> 2198610 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard2.replica_n4, tag=1952087112
   [junit4]   2> 2198610 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 2198609 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard2.replica_n6, tag=1518477714
   [junit4]   2> 2198610 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.nodes_used_collection.shard2.leader, tag=1952087112
   [junit4]   2> 2198610 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.nodes_used_collection.shard2.leader, tag=1518477714
   [junit4]   2> 2198610 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.nodes_used_collection.shard1.replica_n2, tag=1317142161
   [junit4]   2> 2198613 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.nodes_used_collection.shard1.leader, tag=1317142161
   [junit4]   2> 2198613 INFO  (qtp1281029843-16940) [n:127.0.0.1:61108_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=137
   [junit4]   2> 2198618 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 2198618 INFO  (qtp1348226055-16927) [n:127.0.0.1:47626_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard2_replica_n6&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=142
   [junit4]   2> 2198619 WARN  
(zkCallback-2173-thread-2-processing-n:127.0.0.1:47626_solr) 
[n:127.0.0.1:47626_solr    ] o.a.s.c.LeaderElector Our node is no longer in 
line to be leader
   [junit4]   2> 2198630 INFO  (qtp1538750946-16921) [n:127.0.0.1:50406_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard2_replica_n4&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=154
   [junit4]   2> 2198631 WARN  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.LeaderElector Our node is no longer in 
line to be leader
   [junit4]   2> 2198632 INFO  (qtp1603776564-16951) [n:127.0.0.1:37164_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=156
   [junit4]   2> 2199341 INFO  (qtp1603776564-16950) [n:127.0.0.1:37164_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=nodes_used_collection&action=DELETE&wt=javabin&version=2} status=0 
QTime=870
   [junit4]   2> 2199342 INFO  (qtp1603776564-16952) [n:127.0.0.1:37164_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=created_and_deleted&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2199348 INFO  
(OverseerThreadFactory-6178-thread-3-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
created_and_deleted
   [junit4]   2> 2199349 INFO  
(OverseerCollectionConfigSetProcessor-98739306479878155-127.0.0.1:61108_solr-n_0000000000)
 [n:127.0.0.1:61108_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2199572 INFO  
(OverseerStateUpdate-98739306479878155-127.0.0.1:61108_solr-n_0000000000) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"created_and_deleted",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"created_and_deleted_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:61108/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2199775 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=created_and_deleted_shard1_replica_n1&action=CREATE&numShards=1&collection=created_and_deleted&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2199891 INFO  
(zkCallback-2174-thread-1-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/created_and_deleted/state.json] for collection 
[created_and_deleted] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2199891 INFO  
(zkCallback-2174-thread-2-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/created_and_deleted/state.json] for collection 
[created_and_deleted] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2200816 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 2200828 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.s.IndexSchema 
[created_and_deleted_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 2200831 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 2200831 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'created_and_deleted_shard1_replica_n1' using configuration from 
collection created_and_deleted, trusted=true
   [junit4]   2> 2200832 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.created_and_deleted.shard1.replica_n1' (registry 
'solr.core.created_and_deleted.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18d6e326
   [junit4]   2> 2200832 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2200832 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.c.SolrCore 
[[created_and_deleted_shard1_replica_n1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node2/created_and_deleted_shard1_replica_n1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001/tempDir-001/node2/created_and_deleted_shard1_replica_n1/data/]
   [junit4]   2> 2200873 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2200873 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2200895 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 2200895 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_solr 
c:created_and_deleted s:shard1 r:core_node2 
x:created_and_deleted_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 2200897 INFO  (qtp1281029843-16941) [n:127.0.0.1:61108_s

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

t org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 2350945 INFO  (qtp1603776564-16950) [n:127.0.0.1:37164_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:61108_solr&onlyIfLeaderActive=true&core=addReplicaColl_shard1_replica_n1&coreNodeName=core_node13&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=400 QTime=2124
   [junit4]   2> 2351723 WARN  
(zkCallback-2172-thread-3-processing-n:127.0.0.1:50406_solr) 
[n:127.0.0.1:50406_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2351724 INFO  (jetty-closer-2154-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5fae41d3{/solr,null,UNAVAILABLE}
   [junit4]   2> 2351725 WARN  
(zkCallback-2175-thread-8-processing-n:127.0.0.1:37164_solr) 
[n:127.0.0.1:37164_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2351725 INFO  (jetty-closer-2154-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7dd7f6d8{/solr,null,UNAVAILABLE}
   [junit4]   2> 2351726 WARN  
(zkCallback-2174-thread-8-processing-n:127.0.0.1:61108_solr) 
[n:127.0.0.1:61108_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2351727 INFO  (jetty-closer-2154-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7a43b282{/solr,null,UNAVAILABLE}
   [junit4]   2> 2351738 INFO  (jetty-closer-2154-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@17556d51{/solr,null,UNAVAILABLE}
   [junit4]   2> 2351741 ERROR 
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2351742 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3D04F12EB853F3B0]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:51472 51472
   [junit4]   2> 2351766 INFO  (Thread-5051) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:51472 51472
   [junit4]   2> 2351774 WARN  (Thread-5051) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        15      /solr/configs/conf
   [junit4]   2>        12      /solr/aliases.json
   [junit4]   2>        12      /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        64      /solr/collections/addReplicaColl/state.json
   [junit4]   2>        39      /solr/collections/reloaded_collection/state.json
   [junit4]   2>        15      
/solr/collections/acollectionafterbaddelete/state.json
   [junit4]   2>        13      
/solr/collections/halfdeletedcollection/state.json
   [junit4]   2>        12      /solr/clusterstate.json
   [junit4]   2>        5       /solr/collections/withconfigset2/state.json
   [junit4]   2>        5       
/solr/collections/halfcollectionblocker2/state.json
   [junit4]   2>        4       
/solr/collections/halfcollectionblocker/state.json
   [junit4]   2>        4       /solr/collections/halfcollection/state.json
   [junit4]   2>        2       
/solr/collections/awhollynewcollection_0/state.json
   [junit4]   2>        2       
/solr/collections/addReplicaColl/leader_elect/shard1/election/98739306479878156-core_node3-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        298     /solr/collections
   [junit4]   2>        12      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3D04F12EB853F3B0-001
   [junit4]   2> Sep 28, 2017 11:46:47 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=RandomSimilarity(queryNorm=true): {}, locale=sr-ME, timezone=Australia/LHI
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_141 
(64-bit)/cpus=3,threads=1,free=240247216,total=478781440
   [junit4]   2> NOTE: All tests run in this JVM: 
[PeerSyncWithIndexFingerprintCachingTest, TestStressLucene, 
TestSystemIdResolver, ManagedSchemaRoundRobinCloudTest, AddBlockUpdateTest, 
PolyFieldTest, BlockCacheTest, TermVectorComponentTest, TestPushWriter, 
InfixSuggestersTest, LukeRequestHandlerTest, OverseerModifyCollectionTest, 
MetricsConfigTest, SpellingQueryConverterTest, TestSSLRandomization, 
TestSearcherReuse, AliasIntegrationTest, SharedFSAutoReplicaFailoverTest, 
AtomicUpdateProcessorFactoryTest, AssignTest, TestSolr4Spatial, 
TestTestInjection, TestNamedUpdateProcessors, 
ClassificationUpdateProcessorTest, TestHdfsUpdateLog, SolrRequestParserTest, 
TestLeaderElectionWithEmptyReplica, CoreSorterTest, TestFastOutputStream, 
ZkSolrClientTest, SuggesterTSTTest, RollingRestartTest, MBeansHandlerTest, 
PreAnalyzedFieldTest, TestNumericRangeQuery64, UUIDUpdateProcessorFallbackTest, 
TestPolicyCloud, SuggesterFSTTest, TestManagedStopFilterFactory, 
TestFreeTextSuggestions, DocExpirationUpdateProcessorFactoryTest, 
TestDocumentBuilder, TestAuthenticationFramework, GraphQueryTest, 
TestSlowCompositeReaderWrapper, TestSolrCloudWithSecureImpersonation, TestTrie, 
TestBM25SimilarityFactory, ConnectionReuseTest, TestSystemCollAutoCreate, 
TestRandomFaceting, InfoHandlerTest, TestRTimerTree, DebugComponentTest, 
OpenExchangeRatesOrgProviderTest, TestReload, TestComponentsName, 
SignatureUpdateProcessorFactoryTest, ZkFailoverTest, 
SolrCoreCheckLockOnStartupTest, TestUseDocValuesAsStored2, BooleanFieldTest, 
LeaderElectionIntegrationTest, BlobRepositoryCloudTest, 
DistributedFacetPivotSmallTest, TestGraphMLResponseWriter, TestTlogReplica, 
CdcrRequestHandlerTest, DirectSolrSpellCheckerTest, 
DistributedQueryComponentCustomSortTest, TestRecoveryHdfs, 
TestSubQueryTransformer, FullSolrCloudDistribCmdsTest, 
CollectionsAPIAsyncDistributedZkTest, ExitableDirectoryReaderTest, 
TestDistributedGrouping, ParsingFieldUpdateProcessorsTest, SolrJmxReporterTest, 
TestTrieFacet, TestFilteredDocIdSet, TestImpersonationWithHadoopAuth, 
TestFieldCacheSort, TestConfigSetsAPIExclusivity, SubstringBytesRefFilterTest, 
TestTolerantUpdateProcessorRandomCloud, UpdateRequestProcessorFactoryTest, 
TestCollationField, TestSolrQueryResponse, RuleEngineTest, 
TestConfigSetProperties, MoveReplicaTest, SaslZkACLProviderTest, TestZkChroot, 
TestScoreJoinQPScore, OpenCloseCoreStressTest, TestBlobHandler, 
SuggestComponentContextFilterQueryTest, SpellCheckCollatorWithCollapseTest, 
TestFieldCache, TermsComponentTest, TestXmlQParserPlugin, 
TestMultiValuedNumericRangeQuery, TestRealTimeGet, HighlighterMaxOffsetTest, 
AnalyticsQueryTest, TestDistribDocBasedVersion, TestMergePolicyConfig, 
DocumentBuilderTest, CustomCollectionTest, TestPullReplica, 
ReplicationFactorTest, TestNestedDocsSort, DistributedMLTComponentTest, 
FileBasedSpellCheckerTest, DistributedDebugComponentTest, TestIntervalFaceting, 
TestHashQParserPlugin, JsonLoaderTest, BufferStoreTest, TestLegacyFieldCache, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, SpellCheckCollatorTest, 
LeaderFailoverAfterPartitionTest, TestBinaryResponseWriter, TestIndexSearcher, 
TestNRTOpen, TestCloudRecovery, TestConfigOverlay, 
DistributedFacetPivotLongTailTest, TestCryptoKeys, 
FieldMutatingUpdateProcessorTest, TestQueryTypes, PluginInfoTest, 
TestPKIAuthenticationPlugin, TestFiltering, SolrIndexConfigTest, 
SystemInfoHandlerTest, TestSolrIndexConfig, ShardRoutingCustomTest, 
ZkStateWriterTest, TestManagedSynonymGraphFilterFactory, DocValuesMissingTest, 
TestTrackingShardHandlerFactory, SolrCloudExampleTest, ExplicitHLLTest, 
TestPointFields, TestNumericTerms64, TestDistribIDF, 
TestHttpShardHandlerFactory, TestEmbeddedSolrServerAdminHandler, 
ChaosMonkeySafeLeaderTest, TestCustomSort, 
HdfsTlogReplayBufferedWhileIndexingTest, BaseCdcrDistributedZkTest, 
TestRawTransformer, TestPerFieldSimilarity, DistributedTermsComponentTest, 
SpellPossibilityIteratorTest, CoreAdminRequestStatusTest, RequestLoggingTest, 
SearchHandlerTest, TestInPlaceUpdatesStandalone, TestDistributedMissingSort, 
TestReqParamsAPI, TestClassicSimilarityFactory, 
HdfsWriteToMultipleCollectionsTest, TestConfigsApi, TestElisionMultitermQuery, 
TestInfoStreamLogging, BigEndianAscendingWordSerializerTest, 
TestSerializedLuceneMatchVersion, SimpleCollectionCreateDeleteTest, 
DOMUtilTest, V2StandaloneTest, TestNonDefinedSimilarityFactory, TestFastWriter, 
ConvertedLegacyTest, BadIndexSchemaTest, BasicDistributedZk2Test, 
CollectionsAPIDistributedZkTest]
   [junit4] Completed [342/732 (1!)] on J1 in 158.33s, 19 tests, 1 error <<< 
FAILURES!

[...truncated 48044 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to