Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3946/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC

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

Error Message:
Error from server at http://127.0.0.1:56230/solr/awhollynewcollection_0: 
Expected mime type application/octet-stream but got text/html. <html> <head> 
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> 
<title>Error 510 </title> </head> <body> <h2>HTTP ERROR: 510</h2> <p>Problem 
accessing /solr/awhollynewcollection_0/select. Reason: <pre>    
{metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={&quot;awhollynewcollection_0&quot;:5},code=510}</pre></p>
 <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 
9.3.14.v20161028</a><hr/> </body> </html> 

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:56230/solr/awhollynewcollection_0: Expected 
mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 510 </title>
</head>
<body>
<h2>HTTP ERROR: 510</h2>
<p>Problem accessing /solr/awhollynewcollection_0/select. Reason:
<pre>    
{metadata={error-class=org.apache.solr.common.SolrException,root-error-class=org.apache.solr.common.SolrException},msg={&quot;awhollynewcollection_0&quot;:5},code=510}</pre></p>
<hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 
9.3.14.v20161028</a><hr/>
</body>
</html>

        at 
__randomizedtesting.SeedInfo.seed([EF81F54EA752694E:A7F481FAA16146DB]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:595)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:439)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:391)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1364)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1115)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1218)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1218)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1218)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1218)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1218)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1054)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:523)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11468 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/init-core-data-001
   [junit4]   2> 875727 WARN  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 875727 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 875730 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 875731 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001
   [junit4]   2> 875731 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 875732 INFO  (Thread-1374) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 875732 INFO  (Thread-1374) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 875841 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:56226
   [junit4]   2> 875865 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 875865 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 875865 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 875866 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 875870 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1dbd8d51{/solr,null,AVAILABLE}
   [junit4]   2> 875870 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4887d3fb{/solr,null,AVAILABLE}
   [junit4]   2> 875871 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4578d5f{/solr,null,AVAILABLE}
   [junit4]   2> 875871 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3af526ed{HTTP/1.1,[http/1.1]}{127.0.0.1:56229}
   [junit4]   2> 875871 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.e.j.s.Server Started @880965ms
   [junit4]   2> 875871 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56229}
   [junit4]   2> 875872 ERROR (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 875872 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 875872 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 875872 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 875872 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-04-06T01:19:54.469Z
   [junit4]   2> 875873 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4783b6c6{/solr,null,AVAILABLE}
   [junit4]   2> 875873 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2f592d3{HTTP/1.1,[http/1.1]}{127.0.0.1:56230}
   [junit4]   2> 875873 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.e.j.s.Server Started @880967ms
   [junit4]   2> 875873 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56230}
   [junit4]   2> 875873 ERROR (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 875873 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 875874 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 875874 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 875874 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-04-06T01:19:54.471Z
   [junit4]   2> 875879 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@39cb9455{HTTP/1.1,[http/1.1]}{127.0.0.1:56228}
   [junit4]   2> 875879 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.e.j.s.Server Started @880973ms
   [junit4]   2> 875879 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56228}
   [junit4]   2> 875879 ERROR (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 875880 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 875880 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 875880 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 875880 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-04-06T01:19:54.477Z
   [junit4]   2> 875883 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@634de947{HTTP/1.1,[http/1.1]}{127.0.0.1:56231}
   [junit4]   2> 875884 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.e.j.s.Server Started @880977ms
   [junit4]   2> 875884 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56231}
   [junit4]   2> 875884 ERROR (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 875884 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 875885 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 875885 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 875885 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-04-06T01:19:54.482Z
   [junit4]   2> 875888 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 875890 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 875890 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 875891 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 875907 INFO  (jetty-launcher-2220-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56226/solr
   [junit4]   2> 875907 INFO  (jetty-launcher-2220-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56226/solr
   [junit4]   2> 875909 INFO  (jetty-launcher-2220-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56226/solr
   [junit4]   2> 875910 INFO  (jetty-launcher-2220-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56226/solr
   [junit4]   2> 875968 INFO  (jetty-launcher-2220-thread-2) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 875969 INFO  (jetty-launcher-2220-thread-3) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 875969 INFO  (jetty-launcher-2220-thread-4) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 875969 INFO  (jetty-launcher-2220-thread-1) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 875972 INFO  (jetty-launcher-2220-thread-3) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:56229_solr
   [junit4]   2> 875973 INFO  (jetty-launcher-2220-thread-2) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56228_solr
   [junit4]   2> 875973 INFO  (jetty-launcher-2220-thread-1) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56231_solr
   [junit4]   2> 875973 INFO  (jetty-launcher-2220-thread-4) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56230_solr
   [junit4]   2> 875975 INFO  (jetty-launcher-2220-thread-3) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.Overseer Overseer 
(id=97743116326076425-127.0.0.1:56229_solr-n_0000000000) starting
   [junit4]   2> 875977 INFO  
(zkCallback-2242-thread-1-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 875977 INFO  
(zkCallback-2240-thread-1-processing-n:127.0.0.1:56228_solr) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 875977 INFO  
(zkCallback-2239-thread-1-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 875977 INFO  
(zkCallback-2241-thread-1-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 876024 INFO  (jetty-launcher-2220-thread-3) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56229_solr
   [junit4]   2> 876026 INFO  
(zkCallback-2240-thread-1-processing-n:127.0.0.1:56228_solr) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 876026 INFO  
(zkCallback-2241-thread-1-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 876027 INFO  
(zkCallback-2239-thread-1-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 876027 INFO  
(zkCallback-2242-thread-1-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 876104 INFO  (jetty-launcher-2220-thread-2) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node1/.
   [junit4]   2> 876104 INFO  (jetty-launcher-2220-thread-4) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node4/.
   [junit4]   2> 876110 INFO  (jetty-launcher-2220-thread-3) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node2/.
   [junit4]   2> 876176 INFO  (jetty-launcher-2220-thread-1) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node3/.
   [junit4]   2> 876883 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 876885 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56226/solr ready
   [junit4]   2> 876937 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet
   [junit4]   2> 876939 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 876945 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:56228/solr,http://127.0.0.1:56229/solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 876950 INFO  
(OverseerThreadFactory-3277-thread-1-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
nodeset_collection
   [junit4]   2> 876951 WARN  
(OverseerThreadFactory-3277-thread-1-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd It is unusual to 
create a collection (nodeset_collection) without cores.
   [junit4]   2> 877064 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_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> 877064 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=http://127.0.0.1:56228/solr,http://127.0.0.1:56229/solr&wt=javabin&version=2}
 status=0 QTime=119
   [junit4]   2> 877066 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet
   [junit4]   2> 877101 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShouldFailOnExistingCore
   [junit4]   2> 877104 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 877108 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=nodeset_collection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 877113 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_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> 877113 INFO  
(OverseerThreadFactory-3277-thread-2-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 877824 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0 
QTime=716
   [junit4]   2> 877831 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-002&collection.configName=conf&name=halfcollection_shard1_replica1&action=CREATE&numShards=1&collection=halfcollectionblocker&wt=javabin&version=2
   [junit4]   2> 877833 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 877836 INFO  
(OverseerStateUpdate-97743116326076425-127.0.0.1:56229_solr-n_0000000000) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 878864 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 878873 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.s.IndexSchema 
[halfcollection_shard1_replica1] Schema name=minimal
   [junit4]   2> 878878 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 878878 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'halfcollection_shard1_replica1' using 
configuration from collection halfcollectionblocker, trusted=true
   [junit4]   2> 878878 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 878878 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
[[halfcollection_shard1_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node3/halfcollection_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-002/]
   [junit4]   2> 878940 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 878940 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 878943 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 878943 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 878944 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@349734fe[halfcollection_shard1_replica1] main]
   [junit4]   2> 878946 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 878946 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 878946 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 878947 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1563889864586297344
   [junit4]   2> 878947 INFO  
(searcherExecutor-3286-thread-1-processing-n:127.0.0.1:56231_solr 
x:halfcollection_shard1_replica1 c:halfcollectionblocker) 
[n:127.0.0.1:56231_solr c:halfcollectionblocker   
x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
[halfcollection_shard1_replica1] Registered new searcher 
Searcher@349734fe[halfcollection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56231/solr/halfcollection_shard1_replica1/
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:56231/solr/halfcollection_shard1_replica1/ has no replicas
   [junit4]   2> 878958 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 878967 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56231/solr/halfcollection_shard1_replica1/ shard1
   [junit4]   2> 879123 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 879123 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_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> 879126 INFO  (qtp538759068-8755) [n:127.0.0.1:56231_solr 
c:halfcollectionblocker   x:halfcollection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={dataDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-002&collection.configName=conf&name=halfcollection_shard1_replica1&action=CREATE&numShards=1&collection=halfcollectionblocker&wt=javabin&version=2}
 status=0 QTime=1296
   [junit4]   2> 879133 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-003&collection.configName=conf&name=halfcollection_shard1_replica1&action=CREATE&numShards=1&collection=halfcollectionblocker2&wt=javabin&version=2
   [junit4]   2> 879133 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 879136 INFO  
(OverseerStateUpdate-97743116326076425-127.0.0.1:56229_solr-n_0000000000) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 880163 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 880170 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.s.IndexSchema [halfcollection_shard1_replica1] Schema name=minimal
   [junit4]   2> 880175 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 880175 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'halfcollection_shard1_replica1' using 
configuration from collection halfcollectionblocker2, trusted=true
   [junit4]   2> 880176 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 880176 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
[[halfcollection_shard1_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node1/halfcollection_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-003/]
   [junit4]   2> 880253 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 880253 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 880255 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 880255 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 880256 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@351d1aa3[halfcollection_shard1_replica1] main]
   [junit4]   2> 880259 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 880260 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 880260 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 880260 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1563889865963077632
   [junit4]   2> 880260 INFO  
(searcherExecutor-3291-thread-1-processing-n:127.0.0.1:56228_solr 
x:halfcollection_shard1_replica1 c:halfcollectionblocker2) 
[n:127.0.0.1:56228_solr c:halfcollectionblocker2   
x:halfcollection_shard1_replica1] o.a.s.c.SolrCore 
[halfcollection_shard1_replica1] Registered new searcher 
Searcher@351d1aa3[halfcollection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56228/solr/halfcollection_shard1_replica1/
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:56228/solr/halfcollection_shard1_replica1/ has no replicas
   [junit4]   2> 880271 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 880280 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56228/solr/halfcollection_shard1_replica1/ shard1
   [junit4]   2> 880450 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 880453 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr 
c:halfcollectionblocker2   x:halfcollection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={dataDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-003&collection.configName=conf&name=halfcollection_shard1_replica1&action=CREATE&numShards=1&collection=halfcollectionblocker2&wt=javabin&version=2}
 status=0 QTime=1320
   [junit4]   2> 880454 INFO  (qtp65102727-8732) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=halfcollection&action=CREATE&numShards=2&createNodeSet=127.0.0.1:56231_solr,127.0.0.1:56228_solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 880458 INFO  
(OverseerThreadFactory-3277-thread-3-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
halfcollection
   [junit4]   2> 880677 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfcollection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 880678 INFO  (qtp538759068-8756) [n:127.0.0.1:56231_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfcollection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 880679 WARN  (qtp538759068-8756) [n:127.0.0.1:56231_solr    ] 
o.a.s.c.CoreContainer Creating a core with existing name is not allowed
   [junit4]   2> 880681 ERROR (qtp538759068-8756) [n:127.0.0.1:56231_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Core with name 
'halfcollection_shard1_replica1' already exists.
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.create(CoreContainer.java:884)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:370)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:388)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:747)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.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:745)
   [junit4]   2> 
   [junit4]   2> 880681 INFO  (qtp538759068-8756) [n:127.0.0.1:56231_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfcollection_shard1_replica1&action=CREATE&numShards=2&collection=halfcollection&shard=shard1&wt=javabin&version=2}
 status=500 QTime=5
   [junit4]   2> 880682 ERROR (qtp538759068-8756) [n:127.0.0.1:56231_solr    ] 
o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: Core with name 
'halfcollection_shard1_replica1' already exists.
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.create(CoreContainer.java:884)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:88)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:370)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:388)
   [junit4]   2>        at 
org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:173)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:747)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.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:745)
   [junit4]   2> 
   [junit4]   2> 880682 ERROR 
(OverseerThreadFactory-3277-thread-3-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Error 
from shard: http://127.0.0.1:56231/solr
   [junit4]   2> 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:56231/solr: Core with name 
'halfcollection_shard1_replica1' already exists.
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
   [junit4]   2>        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2>        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:163)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 880793 INFO  
(zkCallback-2240-thread-1-processing-n:127.0.0.1:56228_solr) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/halfcollection/state.json] for collection [halfcollection] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 881708 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 881717 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.s.IndexSchema [halfcollection_shard2_replica1] Schema name=minimal
   [junit4]   2> 881721 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 881721 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'halfcollection_shard2_replica1' using 
configuration from collection halfcollection, trusted=true
   [junit4]   2> 881722 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 881723 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] o.a.s.c.SolrCore 
[[halfcollection_shard2_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node1/halfcollection_shard2_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node1/./halfcollection_shard2_replica1/data/]
   [junit4]   2> 881789 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 881789 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 881792 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 881792 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 881792 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2b08e8aa[halfcollection_shard2_replica1] main]
   [junit4]   2> 881794 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 881795 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 881795 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 881795 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1563889867572641792
   [junit4]   2> 881795 INFO  
(searcherExecutor-3296-thread-1-processing-n:127.0.0.1:56228_solr 
x:halfcollection_shard2_replica1 s:shard2 c:halfcollection) 
[n:127.0.0.1:56228_solr c:halfcollection s:shard2  
x:halfcollection_shard2_replica1] o.a.s.c.SolrCore 
[halfcollection_shard2_replica1] Registered new searcher 
Searcher@2b08e8aa[halfcollection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56228/solr/halfcollection_shard2_replica1/
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:56228/solr/halfcollection_shard2_replica1/ has no replicas
   [junit4]   2> 881806 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 881813 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56228/solr/halfcollection_shard2_replica1/ shard2
   [junit4]   2> 881918 INFO  
(zkCallback-2240-thread-1-processing-n:127.0.0.1:56228_solr) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/halfcollection/state.json] for collection [halfcollection] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 881919 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 881923 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr 
c:halfcollection s:shard2  x:halfcollection_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=halfcollection_shard2_replica1&action=CREATE&numShards=2&collection=halfcollection&shard=shard2&wt=javabin&version=2}
 status=0 QTime=1246
   [junit4]   2> 881923 ERROR 
(OverseerThreadFactory-3277-thread-3-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Cleaning 
up collection [halfcollection].
   [junit4]   2> 881925 INFO  
(OverseerThreadFactory-3277-thread-3-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 881937 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollection.shard2.replica1, tag=null
   [junit4]   2> 881938 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.c.SolrCore [halfcollection_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@78a32276
   [junit4]   2> 881939 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollection.shard2.replica1, tag=2023957110
   [junit4]   2> 881950 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=halfcollection_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=23
   [junit4]   2> 882668 INFO  
(OverseerThreadFactory-3277-thread-3-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Cleaned up artifacts 
for failed create collection for [halfcollection]
   [junit4]   2> 882672 INFO  (qtp65102727-8732) [n:127.0.0.1:56228_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> 884475 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 912805 ERROR (qtp65102727-8732) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Timed out waiting for new collection's replicas to 
become ACTIVE with timeout=30
   [junit4]   2> 912806 INFO  (qtp65102727-8732) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=halfcollection&action=CREATE&numShards=2&createNodeSet=127.0.0.1:56231_solr,127.0.0.1:56228_solr&wt=javabin&version=2}
 status=0 QTime=32351
   [junit4]   2> 912806 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateShouldFailOnExistingCore-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCreateShouldFailOnExistingCore
   [junit4]   2> 912840 INFO  
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting deleteCollectionOnlyInZk
   [junit4]   2> 912842 INFO  
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 912845 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=halfcollectionblocker2&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 912851 INFO  
(OverseerThreadFactory-3277-thread-4-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 912858 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollectionblocker2.shard1.core_node1, tag=null
   [junit4]   2> 912858 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.c.SolrCore [halfcollection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@49b20447
   [junit4]   2> 912860 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollectionblocker2.shard1.core_node1, tag=1236403271
   [junit4]   2> 912863 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=halfcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 913587 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=halfcollectionblocker2&action=DELETE&wt=javabin&version=2} 
status=0 QTime=742
   [junit4]   2> 913588 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=halfcollectionblocker&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 913592 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 913592 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 913595 INFO  (qtp538759068-8758) [n:127.0.0.1:56231_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollectionblocker.shard1.core_node1, tag=null
   [junit4]   2> 913596 INFO  (qtp538759068-8758) [n:127.0.0.1:56231_solr    ] 
o.a.s.c.SolrCore [halfcollection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2b37cfea
   [junit4]   2> 913597 INFO  (qtp538759068-8758) [n:127.0.0.1:56231_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.halfcollectionblocker.shard1.core_node1, tag=725077994
   [junit4]   2> 913600 INFO  (qtp538759068-8758) [n:127.0.0.1:56231_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=halfcollection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 914318 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=halfcollectionblocker&action=DELETE&wt=javabin&version=2} status=0 
QTime=730
   [junit4]   2> 914325 INFO  (qtp65102727-8733) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 914329 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 914336 INFO  (qtp65102727-8733) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=10
   [junit4]   2> 914336 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 914336 INFO  (qtp65102727-8726) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 914337 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=onlyinzk&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 914341 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
onlyinzk
   [junit4]   2> 914342 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 914566 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2
   [junit4]   2> 914567 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2
   [junit4]   2> 914568 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 914678 INFO  
(zkCallback-2242-thread-1-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 914678 INFO  
(zkCallback-2241-thread-1-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 915600 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.0.0
   [junit4]   2> 915600 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.0.0
   [junit4]   2> 915609 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema 
[onlyinzk_shard1_replica1] Schema name=minimal
   [junit4]   2> 915609 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema 
[onlyinzk_shard2_replica1] Schema name=minimal
   [junit4]   2> 915630 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 915630 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.CoreContainer Creating 
SolrCore 'onlyinzk_shard1_replica1' using configuration from collection 
onlyinzk, trusted=true
   [junit4]   2> 915630 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 915630 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.CoreContainer Creating 
SolrCore 'onlyinzk_shard2_replica1' using configuration from collection 
onlyinzk, trusted=true
   [junit4]   2> 915630 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 915630 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 915631 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore 
[[onlyinzk_shard1_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node3/onlyinzk_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node3/./onlyinzk_shard1_replica1/data/]
   [junit4]   2> 915631 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore 
[[onlyinzk_shard2_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node4/onlyinzk_shard2_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001/tempDir-001/node4/./onlyinzk_shard2_replica1/data/]
   [junit4]   2> 915712 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 915712 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 915712 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 915712 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 915717 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 915717 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 915718 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 915718 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 915718 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@36df2d09[onlyinzk_shard1_replica1] main]
   [junit4]   2> 915718 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@a70e0da[onlyinzk_shard2_replica1] main]
   [junit4]   2> 915720 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 915720 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 915721 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 915721 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 915721 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 915722 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 915722 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1563889903147679744
   [junit4]   2> 915722 INFO  
(searcherExecutor-3301-thread-1-processing-n:127.0.0.1:56231_solr 
x:onlyinzk_shard1_replica1 s:shard1 c:onlyinzk) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SolrCore 
[onlyinzk_shard1_replica1] Registered new searcher 
Searcher@36df2d09[onlyinzk_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 915722 INFO  
(searcherExecutor-3302-thread-1-processing-n:127.0.0.1:56230_solr 
x:onlyinzk_shard2_replica1 s:shard2 c:onlyinzk) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SolrCore 
[onlyinzk_shard2_replica1] Registered new searcher 
Searcher@a70e0da[onlyinzk_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 915722 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1563889903147679744
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync 
replicas to http://127.0.0.1:56231/solr/onlyinzk_shard1_replica1/
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:56231/solr/onlyinzk_shard1_replica1/ has no replicas
   [junit4]   2> 915733 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync 
replicas to http://127.0.0.1:56230/solr/onlyinzk_shard2_replica1/
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:56230/solr/onlyinzk_shard2_replica1/ has no replicas
   [junit4]   2> 915733 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 915740 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56231/solr/onlyinzk_shard1_replica1/ shard1
   [junit4]   2> 915741 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56230/solr/onlyinzk_shard2_replica1/ shard2
   [junit4]   2> 915851 INFO  
(zkCallback-2242-thread-1-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 915851 INFO  
(zkCallback-2241-thread-1-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 915905 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 915905 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 915910 INFO  (qtp538759068-8759) [n:127.0.0.1:56231_solr 
c:onlyinzk s:shard1  x:onlyinzk_shard1_replica1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard1_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard1&wt=javabin&version=2}
 status=0 QTime=1345
   [junit4]   2> 915910 INFO  (qtp912984427-8737) [n:127.0.0.1:56230_solr 
c:onlyinzk s:shard2  x:onlyinzk_shard2_replica1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=onlyinzk_shard2_replica1&action=CREATE&numShards=2&collection=onlyinzk&shard=shard2&wt=javabin&version=2}
 status=0 QTime=1343
   [junit4]   2> 915916 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_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> 916021 INFO  
(zkCallback-2241-thread-1-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 916021 INFO  
(zkCallback-2242-thread-1-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/onlyinzk/state.json] for collection [onlyinzk] has occurred - 
updating... (live nodes size: [4])
   [junit4]   2> 916348 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000012 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 916927 INFO  (qtp65102727-8728) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=onlyinzk&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=2589
   [junit4]   2> 916928 INFO  (qtp65102727-8732) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params 
action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 916928 INFO  (qtp65102727-8732) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 916929 INFO  
(TEST-CollectionsAPIDistributedZkTest.deleteCollectionOnlyInZk-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending deleteCollectionOnlyInZk
   [junit4]   2> 916977 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroNumShards
   [junit4]   2> 916981 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 916984 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=onlyinzk&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 916989 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 916991 INFO  (qtp538759068-8760) [n:127.0.0.1:56231_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.onlyinzk.shard1.replica1, tag=null
   [junit4]   2> 916991 INFO  (qtp912984427-8739) [n:127.0.0.1:56230_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.onlyinzk.shard2.replica1, tag=null
   [junit4]   2> 916992 INFO  (qtp538759068-8760) [n:127.0.0.1:56231_solr    ] 
o.a.s.c.SolrCore [onlyinzk_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5d275830
   [junit4]   2> 916992 INFO  (qtp912984427-8739) [n:127.0.0.1:56230_solr    ] 
o.a.s.c.SolrCore [onlyinzk_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@44b78856
   [junit4]   2> 916994 INFO  (qtp538759068-8760) [n:127.0.0.1:56231_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.onlyinzk.shard1.replica1, tag=1562859568
   [junit4]   2> 916995 INFO  (qtp912984427-8739) [n:127.0.0.1:56230_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.onlyinzk.shard2.replica1, tag=1152878678
   [junit4]   2> 917001 INFO  (qtp538759068-8760) [n:127.0.0.1:56231_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=onlyinzk_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=10
   [junit4]   2> 917004 INFO  (qtp912984427-8739) [n:127.0.0.1:56230_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=onlyinzk_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=13
   [junit4]   2> 917727 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=onlyinzk&action=DELETE&wt=javabin&version=2} status=0 QTime=742
   [junit4]   2> 917728 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 917731 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
acollection
   [junit4]   2> 917732 ERROR 
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler 
Collection: acollection operation: create 
failed:org.apache.solr.common.SolrException: numShards must be > 0
   [junit4]   2>        at 
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:124)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:225)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 917732 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000014 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 917741 INFO  (qtp65102727-8729) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&numShards=0&wt=javabin&version=2}
 status=400 QTime=12
   [junit4]   2> 917742 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroNumShards
   [junit4]   2> 917776 INFO  
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testTooManyReplicas
   [junit4]   2> 917778 INFO  
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 917781 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 917785 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection
   [junit4]   2> 917785 WARN  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Specified 
replicationFactor of 10 on collection collection is higher than or equal to the 
number of Solr instances currently live or live and part of your 
createNodeSet(4). It's unusual to run two replica of the same slice on the same 
Solr-instance.
   [junit4]   2> 917785 ERROR 
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerCollectionMessageHandler 
Collection: collection operation: create 
failed:org.apache.solr.common.SolrException: Cannot create collection 
collection. Value of maxShardsPerNode is 1, and the number of nodes currently 
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to 
be created. Value of numShards is 2 and value of replicationFactor is 10. This 
requires 20 shards to be created (higher than the allowed number)
   [junit4]   2>        at 
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:225)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 917785 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000016 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 917789 INFO  (qtp65102727-8731) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=10&collection.configName=conf&name=collection&action=CREATE&numShards=2&wt=javabin&version=2}
 status=400 QTime=8
   [junit4]   2> 917790 INFO  
(TEST-CollectionsAPIDistributedZkTest.testTooManyReplicas-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testTooManyReplicas
   [junit4]   2> 917833 INFO  
(TEST-CollectionsAPIDistributedZkTest.testMissingNumShards-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testMissingNumShards
   [junit4]   2> 917835 INFO  
(TEST-CollectionsAPIDistributedZkTest.testMissingNumShards-seed#[EF81F54EA752694E])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 917838 INFO  (qtp65102727-8733) [n:127.0.0.1:56228_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=10&collection.configName=conf&name=acollection&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 917841 INFO  
(OverseerThreadFactory-3277-thread-5-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
acollection
   [junit

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

6229_solr) [n:127.0.0.1:56229_solr    ] 
o.a.s.c.OverseerCollectionMessageHandler Collection: oversharded operation: 
create failed:org.apache.solr.common.SolrException: Cannot create collection 
oversharded. Value of maxShardsPerNode is 1, and the number of nodes currently 
live or live and part of your createNodeSet is 4. This allows a maximum of 4 to 
be created. Value of numShards is 3 and value of replicationFactor is 2. This 
requires 6 shards to be created (higher than the allowed number)
   [junit4]   2>        at 
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:155)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:225)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 1020386 INFO  
(OverseerCollectionConfigSetProcessor-97743116326076425-127.0.0.1:56229_solr-n_0000000000)
 [n:127.0.0.1:56229_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000072 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1020390 INFO  (qtp65102727-8730) [n:127.0.0.1:56228_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=oversharded&action=CREATE&numShards=3&wt=javabin&version=2}
 status=400 QTime=8
   [junit4]   2> 1020391 INFO  
(TEST-CollectionsAPIDistributedZkTest.testMaxNodesPerShard-seed#[EF81F54EA752694E])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testMaxNodesPerShard
   [junit4]   2> 1020400 INFO  (jetty-closer-2221-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@3af526ed{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1020401 INFO  (jetty-closer-2221-thread-2) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@39cb9455{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1020402 INFO  (jetty-closer-2221-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@634de947{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1020403 INFO  (jetty-closer-2221-thread-3) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=128808366
   [junit4]   2> 1020403 INFO  (jetty-closer-2221-thread-2) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=937201374
   [junit4]   2> 1020404 INFO  (jetty-closer-2221-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1020404 INFO  (jetty-closer-2221-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1020404 INFO  (jetty-closer-2221-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1111121922
   [junit4]   2> 1020404 INFO  (jetty-closer-2221-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1020405 INFO  (jetty-closer-2221-thread-4) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@23e2f39b{HTTP/1.1,[http/1.1]}{127.0.0.1:56230}
   [junit4]   2> 1020406 INFO  (jetty-closer-2221-thread-4) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=160949680
   [junit4]   2> 1020406 INFO  (jetty-closer-2221-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1020407 INFO  (jetty-closer-2221-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1020407 INFO  (jetty-closer-2221-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1020408 INFO  (jetty-closer-2221-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1020410 INFO  (jetty-closer-2221-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1020413 WARN  
(zkCallback-2240-thread-4-processing-n:127.0.0.1:56228_solr) 
[n:127.0.0.1:56228_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1020415 INFO  (jetty-closer-2221-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1dbd8d51{/solr,null,UNAVAILABLE}
   [junit4]   2> 1020415 INFO  (jetty-closer-2221-thread-3) [    ] 
o.a.s.c.Overseer Overseer 
(id=97743116326076425-127.0.0.1:56229_solr-n_0000000000) closing
   [junit4]   2> 1020416 INFO  
(OverseerStateUpdate-97743116326076425-127.0.0.1:56229_solr-n_0000000000) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:56229_solr
   [junit4]   2> 1020416 INFO  
(zkCallback-2239-thread-7-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (1)
   [junit4]   2> 1020421 WARN  
(zkCallback-2239-thread-7-processing-n:127.0.0.1:56229_solr) 
[n:127.0.0.1:56229_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1020422 INFO  (jetty-closer-2221-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4887d3fb{/solr,null,UNAVAILABLE}
   [junit4]   2> 1021914 WARN  
(zkCallback-2241-thread-3-processing-n:127.0.0.1:56231_solr) 
[n:127.0.0.1:56231_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1021914 WARN  
(zkCallback-2277-thread-5-processing-n:127.0.0.1:56230_solr) 
[n:127.0.0.1:56230_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1021915 INFO  (jetty-closer-2221-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4783b6c6{/solr,null,UNAVAILABLE}
   [junit4]   2> 1021915 INFO  (jetty-closer-2221-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@595e0ef5{/solr,null,UNAVAILABLE}
   [junit4]   2> 1021918 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:56226 56226
   [junit4]   2> 1021920 INFO  (Thread-1426) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:56226 56226
   [junit4]   2> 1032123 WARN  (Thread-1426) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        21      /solr/collections
   [junit4]   2>        5       /solr/overseer/queue
   [junit4]   2> 
   [junit4]   2> 1032123 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[EF81F54EA752694E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_EF81F54EA752694E-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{_version_=Lucene50(blocksize=128), id=PostingsFormat(name=Memory)}, 
docValues:{}, maxPointsInLeafNode=368, maxMBSortInHeap=5.582123142855667, 
sim=RandomSimilarity(queryNorm=false): {}, locale=ar-MA, timezone=America/Thule
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=358730456,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [OverseerTaskQueueTest, 
TestDelegationWithHadoopAuth, TestFuzzyAnalyzedSuggestions, 
SubstringBytesRefFilterTest, TestSubQueryTransformer, TestTrie, 
FieldMutatingUpdateProcessorTest, TestJmxMonitoredMap, TestMacros, 
RAMDirectoryFactoryTest, SpellCheckCollatorTest, TestJavabinTupleStreamParser, 
SpellCheckComponentTest, BasicDistributedZk2Test, 
ParsingFieldUpdateProcessorsTest, ConfigureRecoveryStrategyTest, 
TestCodecSupport, TestCollectionAPIs, SolrXmlInZkTest, TestCryptoKeys, 
CdcrVersionReplicationTest, TestDocTermOrds, URLClassifyProcessorTest, 
TestRequestStatusCollectionAPI, TestDefaultStatsCache, RulesTest, 
TestAnalyzedSuggestions, OverseerRolesTest, SolrCloudReportersTest, 
CacheHeaderTest, TestSolrConfigHandlerCloud, TestRangeQuery, 
TestReloadDeadlock, LeaderInitiatedRecoveryOnShardRestartTest, DeleteNodeTest, 
TestQueryTypes, LeaderFailureAfterFreshStartTest, FullHLLTest, TestJsonRequest, 
TestJoin, TestFieldSortValues, TestGraphTermsQParserPlugin, CursorMarkTest, 
SortSpecParsingTest, TestExpandComponent, BasicFunctionalityTest, 
NotRequiredUniqueKeyTest, ChaosMonkeySafeLeaderTest, 
LeaderElectionIntegrationTest, TestCloudRecovery, TestPathTrie, 
BadComponentTest, SuggesterWFSTTest, CoreAdminHandlerTest, 
DistributedFacetPivotLargeTest, ExternalFileFieldSortTest, 
HdfsUnloadDistributedZkTest, TestDynamicFieldCollectionResource, 
TestRawResponseWriter, QueryParsingTest, TestAtomicUpdateErrorCases, 
TestFieldCacheSortRandom, TestRandomFaceting, MetricsHandlerTest, 
TestDistributedMissingSort, TestSystemIdResolver, TestSolr4Spatial2, 
TestSchemaNameResource, TestSolrQueryParserResource, TestInitQParser, 
TestCustomStream, TestValueSourceCache, TestSearchPerf, SimpleMLTQParserTest, 
AsyncCallRequestStatusResponseTest, CdcrReplicationHandlerTest, TestXmlQParser, 
BasicAuthStandaloneTest, MergeStrategyTest, RegexBoostProcessorTest, 
CollectionsAPIDistributedZkTest]
   [junit4] Completed [186/702 (1!)] on J1 in 156.41s, 20 tests, 1 error <<< 
FAILURES!

[...truncated 45001 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to