Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.6-Linux/148/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseParallelGC

2 tests failed.
FAILED:  
org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster

Error Message:
Document mismatch on target after sync expected:<2000> but was:<1901>

Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync 
expected:<2000> but was:<1901>
        at 
__randomizedtesting.SeedInfo.seed([FAF5D9D5E487C171:2EB0928C03D1728A]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster(CdcrBootstrapTest.java:309)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI

Error Message:
Something is broken in the assert for no shards using the same indexDir - 
probably something was changed in the attributes published in the MBean of 
SolrCore : {}

Stack Trace:
java.lang.AssertionError: Something is broken in the assert for no shards using 
the same indexDir - probably something was changed in the attributes published 
in the MBean of SolrCore : {}
        at 
__randomizedtesting.SeedInfo.seed([FAF5D9D5E487C171:B280AD61E2B4EEE4]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.checkNoTwoShardsUseTheSameIndexDir(CollectionsAPIDistributedZkTest.java:646)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:524)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12012 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/init-core-data-001
   [junit4]   2> 1046856 WARN  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 1046856 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1046857 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1046858 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001
   [junit4]   2> 1046858 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1046858 INFO  (Thread-3402) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1046858 INFO  (Thread-3402) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1046859 ERROR (Thread-3402) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1046958 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46639
   [junit4]   2> 1046962 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1046962 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1046969 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1046969 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1046970 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@127a2b6{/solr,null,AVAILABLE}
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@7dd592{SSL,[ssl, 
http/1.1]}{127.0.0.1:45345}
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.e.j.s.Server Started @1048687ms
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45345}
   [junit4]   2> 1046972 ERROR (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.1
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1046972 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-07T00:49:42.433Z
   [junit4]   2> 1046973 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1a1d38{/solr,null,AVAILABLE}
   [junit4]   2> 1046973 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@14923ac{SSL,[ssl, 
http/1.1]}{127.0.0.1:43631}
   [junit4]   2> 1046973 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.e.j.s.Server Started @1048689ms
   [junit4]   2> 1046973 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43631}
   [junit4]   2> 1046974 ERROR (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1046974 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.1
   [junit4]   2> 1046974 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1046974 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1046974 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-07T00:49:42.435Z
   [junit4]   2> 1046974 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@9e30bb{/solr,null,AVAILABLE}
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@ac533d{/solr,null,AVAILABLE}
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1d8c84b{SSL,[ssl, 
http/1.1]}{127.0.0.1:40505}
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@6edec1{SSL,[ssl, 
http/1.1]}{127.0.0.1:41643}
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.e.j.s.Server Started @1048700ms
   [junit4]   2> 1046984 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.e.j.s.Server Started @1048700ms
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40505}
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41643}
   [junit4]   2> 1046985 ERROR (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1046985 ERROR (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.1
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.1
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-07T00:49:42.446Z
   [junit4]   2> 1046985 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-07T00:49:42.446Z
   [junit4]   2> 1046986 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1046988 INFO  (jetty-launcher-1503-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46639/solr
   [junit4]   2> 1046989 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1046995 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1046995 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1046996 INFO  (jetty-launcher-1503-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46639/solr
   [junit4]   2> 1046996 INFO  (jetty-launcher-1503-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46639/solr
   [junit4]   2> 1046996 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1047008 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1047011 INFO  (jetty-launcher-1503-thread-2) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1047011 INFO  (jetty-launcher-1503-thread-2) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:45345_solr
   [junit4]   2> 1047011 INFO  (jetty-launcher-1503-thread-2) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.Overseer Overseer 
(id=98614993375657992-127.0.0.1:45345_solr-n_0000000000) starting
   [junit4]   2> 1047016 INFO  (jetty-launcher-1503-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46639/solr
   [junit4]   2> 1047019 INFO  (jetty-launcher-1503-thread-3) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1047019 INFO  (jetty-launcher-1503-thread-4) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1047019 INFO  (jetty-launcher-1503-thread-4) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43631_solr
   [junit4]   2> 1047020 INFO  (jetty-launcher-1503-thread-3) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40505_solr
   [junit4]   2> 1047028 INFO  
(zkCallback-1519-thread-1-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1047028 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1047028 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1047029 INFO  (jetty-launcher-1503-thread-2) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45345_solr
   [junit4]   2> 1047029 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1047029 INFO  
(zkCallback-1519-thread-1-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1047029 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1047041 INFO  (jetty-launcher-1503-thread-1) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1047042 INFO  (jetty-launcher-1503-thread-1) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1047043 INFO  (jetty-launcher-1503-thread-1) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41643_solr
   [junit4]   2> 1047043 INFO  
(zkCallback-1519-thread-1-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1047048 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1047048 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1047056 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1047120 INFO  (jetty-launcher-1503-thread-4) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node4/.
   [junit4]   2> 1047122 INFO  (jetty-launcher-1503-thread-1) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node1/.
   [junit4]   2> 1047177 INFO  (jetty-launcher-1503-thread-3) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node3/.
   [junit4]   2> 1047193 INFO  (jetty-launcher-1503-thread-2) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node2/.
   [junit4]   2> 1047199 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1047199 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46639/solr ready
   [junit4]   2> 1047222 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroNumShards
   [junit4]   2> 1047223 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1047285 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_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> 1047286 INFO  
(OverseerThreadFactory-4072-thread-1-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
acollection
   [junit4]   2> 1047287 ERROR 
(OverseerThreadFactory-4072-thread-1-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_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:226)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1047288 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_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=3
   [junit4]   2> 1047289 INFO  
(TEST-CollectionsAPIDistributedZkTest.testZeroNumShards-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroNumShards
   [junit4]   2> 1047310 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCoresAreDistributedAcrossNodes
   [junit4]   2> 1047310 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1047312 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&name=nodes_used_collection&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1047313 INFO  
(OverseerThreadFactory-4072-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
nodes_used_collection
   [junit4]   2> 1047313 INFO  
(OverseerCollectionConfigSetProcessor-98614993375657992-127.0.0.1:45345_solr-n_0000000000)
 [n:127.0.0.1:45345_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> 1047419 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1047482 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1047486 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1047486 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1053419 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1053420 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1053422 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1053482 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1053483 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1053486 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1053486 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1053486 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1053486 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1053589 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1053589 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1053589 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1053589 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054432 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 1054440 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.s.IndexSchema [nodes_used_collection_shard2_replica1] Schema name=minimal
   [junit4]   2> 1054444 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1054444 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'nodes_used_collection_shard2_replica1' 
using configuration from collection nodes_used_collection, trusted=true
   [junit4]   2> 1054444 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1054444 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.SolrCore [[nodes_used_collection_shard2_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node1/nodes_used_collection_shard2_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node1/./nodes_used_collection_shard2_replica1/data/]
   [junit4]   2> 1054480 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1054480 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1054481 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1054481 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1054481 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@a28611[nodes_used_collection_shard2_replica1] main]
   [junit4]   2> 1054482 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1054482 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1054483 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1054483 INFO  
(searcherExecutor-4081-thread-1-processing-n:127.0.0.1:41643_solr 
x:nodes_used_collection_shard2_replica1 s:shard2 c:nodes_used_collection) 
[n:127.0.0.1:41643_solr c:nodes_used_collection s:shard2  
x:nodes_used_collection_shard2_replica1] o.a.s.c.SolrCore 
[nodes_used_collection_shard2_replica1] Registered new searcher 
Searcher@a28611[nodes_used_collection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1054483 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577839902005919744
   [junit4]   2> 1054486 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1054489 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 1054497 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 1054497 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
   [junit4]   2> 1054499 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.s.IndexSchema [nodes_used_collection_shard1_replica1] Schema name=minimal
   [junit4]   2> 1054501 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1054501 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'nodes_used_collection_shard1_replica1' 
using configuration from collection nodes_used_collection, trusted=true
   [junit4]   2> 1054501 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1054501 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.SolrCore [[nodes_used_collection_shard1_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node2/nodes_used_collection_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node2/./nodes_used_collection_shard1_replica1/data/]
   [junit4]   2> 1054502 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.s.IndexSchema [nodes_used_collection_shard2_replica2] Schema name=minimal
   [junit4]   2> 1054502 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.s.IndexSchema [nodes_used_collection_shard1_replica2] Schema name=minimal
   [junit4]   2> 1054503 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1054503 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1054503 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'nodes_used_collection_shard2_replica2' 
using configuration from collection nodes_used_collection, trusted=true
   [junit4]   2> 1054503 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'nodes_used_collection_shard1_replica2' 
using configuration from collection nodes_used_collection, trusted=true
   [junit4]   2> 1054503 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1054503 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1054503 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.c.SolrCore [[nodes_used_collection_shard2_replica2] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node3/nodes_used_collection_shard2_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node3/./nodes_used_collection_shard2_replica2/data/]
   [junit4]   2> 1054503 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.c.SolrCore [[nodes_used_collection_shard1_replica2] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node4/nodes_used_collection_shard1_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node4/./nodes_used_collection_shard1_replica2/data/]
   [junit4]   2> 1054523 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1054523 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1054523 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1054523 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1054523 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1054523 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1054524 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1054524 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@10d2b01[nodes_used_collection_shard1_replica1] main]
   [junit4]   2> 1054524 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@715f39[nodes_used_collection_shard1_replica2] main]
   [junit4]   2> 1054524 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@11af0fd[nodes_used_collection_shard2_replica2] main]
   [junit4]   2> 1054525 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1054525 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1054525 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1054525 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1054525 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1054525 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1054525 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1054525 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1054525 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1054525 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577839902049959936
   [junit4]   2> 1054525 INFO  
(searcherExecutor-4087-thread-1-processing-n:127.0.0.1:40505_solr 
x:nodes_used_collection_shard2_replica2 s:shard2 c:nodes_used_collection) 
[n:127.0.0.1:40505_solr c:nodes_used_collection s:shard2  
x:nodes_used_collection_shard2_replica2] o.a.s.c.SolrCore 
[nodes_used_collection_shard2_replica2] Registered new searcher 
Searcher@11af0fd[nodes_used_collection_shard2_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1054525 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577839902049959936
   [junit4]   2> 1054525 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577839902049959936
   [junit4]   2> 1054527 INFO  
(searcherExecutor-4088-thread-1-processing-n:127.0.0.1:43631_solr 
x:nodes_used_collection_shard1_replica2 s:shard1 c:nodes_used_collection) 
[n:127.0.0.1:43631_solr c:nodes_used_collection s:shard1  
x:nodes_used_collection_shard1_replica2] o.a.s.c.SolrCore 
[nodes_used_collection_shard1_replica2] Registered new searcher 
Searcher@715f39[nodes_used_collection_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1054527 INFO  
(searcherExecutor-4086-thread-1-processing-n:127.0.0.1:45345_solr 
x:nodes_used_collection_shard1_replica1 s:shard1 c:nodes_used_collection) 
[n:127.0.0.1:45345_solr c:nodes_used_collection s:shard1  
x:nodes_used_collection_shard1_replica1] o.a.s.c.SolrCore 
[nodes_used_collection_shard1_replica1] Registered new searcher 
Searcher@10d2b01[nodes_used_collection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1054529 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1054529 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1054529 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:45345/solr/nodes_used_collection_shard1_replica1/
   [junit4]   2> 1054529 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.PeerSync PeerSync: core=nodes_used_collection_shard1_replica1 
url=https://127.0.0.1:45345/solr START 
replicas=[https://127.0.0.1:43631/solr/nodes_used_collection_shard1_replica2/] 
nUpdates=100
   [junit4]   2> 1054531 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.u.PeerSync PeerSync: core=nodes_used_collection_shard1_replica1 
url=https://127.0.0.1:45345/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1054535 INFO  (qtp4044856-10438) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1 r:core_node3 
x:nodes_used_collection_shard1_replica2] o.a.s.c.S.Request 
[nodes_used_collection_shard1_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1054535 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1054535 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1054535 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1054537 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:45345/solr/nodes_used_collection_shard1_replica1/ shard1
   [junit4]   2> 1054638 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054638 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054638 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054638 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054687 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1054689 INFO  (qtp30714718-10418) [n:127.0.0.1:45345_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2}
 status=0 QTime=7207
   [junit4]   2> 1054790 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054790 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054790 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054790 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1054986 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1054986 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1054986 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:41643/solr/nodes_used_collection_shard2_replica1/
   [junit4]   2> 1054987 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.PeerSync PeerSync: core=nodes_used_collection_shard2_replica1 
url=https://127.0.0.1:41643/solr START 
replicas=[https://127.0.0.1:40505/solr/nodes_used_collection_shard2_replica2/] 
nUpdates=100
   [junit4]   2> 1054987 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.u.PeerSync PeerSync: core=nodes_used_collection_shard2_replica1 
url=https://127.0.0.1:41643/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1054992 INFO  (qtp18293775-10432) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2 r:core_node4 
x:nodes_used_collection_shard2_replica2] o.a.s.c.S.Request 
[nodes_used_collection_shard2_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1054993 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1054993 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1054993 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1054997 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:41643/solr/nodes_used_collection_shard2_replica1/ shard2
   [junit4]   2> 1055099 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055099 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055099 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055099 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055148 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1055149 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica1&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2}
 status=0 QTime=7730
   [junit4]   2> 1055251 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055251 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055251 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055251 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055530 INFO  (qtp18293775-10431) [n:127.0.0.1:40505_solr 
c:nodes_used_collection s:shard2  x:nodes_used_collection_shard2_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard2_replica2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard2&wt=javabin&version=2}
 status=0 QTime=8044
   [junit4]   2> 1055530 INFO  (qtp4044856-10428) [n:127.0.0.1:43631_solr 
c:nodes_used_collection s:shard1  x:nodes_used_collection_shard1_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=nodes_used_collection_shard1_replica2&action=CREATE&numShards=2&collection=nodes_used_collection&shard=shard1&wt=javabin&version=2}
 status=0 QTime=8045
   [junit4]   2> 1055532 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_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> 1055633 INFO  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055633 INFO  
(zkCallback-1528-thread-1-processing-n:127.0.0.1:41643_solr) 
[n:127.0.0.1:41643_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055633 INFO  
(zkCallback-1519-thread-2-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1055633 INFO  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/nodes_used_collection/state.json] for collection 
[nodes_used_collection] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1056532 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=nodes_used_collection&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=9219
   [junit4]   2> 1056533 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCoresAreDistributedAcrossNodes-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCoresAreDistributedAcrossNodes
   [junit4]   2> 1056594 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testNoCollectionSpecified
   [junit4]   2> 1056594 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1056596 INFO  (qtp16693727-10450) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=nodes_used_collection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1056597 INFO  
(OverseerCollectionConfigSetProcessor-98614993375657992-127.0.0.1:45345_solr-n_0000000000)
 [n:127.0.0.1:45345_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> 1056597 INFO  
(OverseerThreadFactory-4072-thread-3-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1056602 INFO  (qtp30714718-10423) [n:127.0.0.1:45345_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard1.replica1
   [junit4]   2> 1056602 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard2.replica1
   [junit4]   2> 1056602 INFO  (qtp30714718-10423) [n:127.0.0.1:45345_solr    ] 
o.a.s.c.SolrCore [nodes_used_collection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@57df58
   [junit4]   2> 1056602 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.c.SolrCore [nodes_used_collection_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@abe309
   [junit4]   2> 1056602 INFO  (qtp4044856-10437) [n:127.0.0.1:43631_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard1.replica2
   [junit4]   2> 1056602 INFO  (qtp18293775-10456) [n:127.0.0.1:40505_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard2.replica2
   [junit4]   2> 1056603 INFO  (qtp4044856-10437) [n:127.0.0.1:43631_solr    ] 
o.a.s.c.SolrCore [nodes_used_collection_shard1_replica2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@198e1cf
   [junit4]   2> 1056603 INFO  (qtp18293775-10456) [n:127.0.0.1:40505_solr    ] 
o.a.s.c.SolrCore [nodes_used_collection_shard2_replica2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6b12dd
   [junit4]   2> 1056603 INFO  (qtp30714718-10423) [n:127.0.0.1:45345_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard1.replica1
   [junit4]   2> 1056603 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard2.replica1
   [junit4]   2> 1056603 INFO  (qtp18293775-10456) [n:127.0.0.1:40505_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard2.replica2
   [junit4]   2> 1056603 INFO  (qtp4044856-10437) [n:127.0.0.1:43631_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.nodes_used_collection.shard1.replica2
   [junit4]   2> 1056606 INFO  (qtp18293775-10456) [n:127.0.0.1:40505_solr    ] 
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 1056606 INFO  (qtp4044856-10437) [n:127.0.0.1:43631_solr    ] 
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 1056606 WARN  
(zkCallback-1520-thread-1-processing-n:127.0.0.1:43631_solr) 
[n:127.0.0.1:43631_solr    ] o.a.s.c.LeaderElector 
   [junit4]   2> org.apache.solr.common.SolrException: SolrCore not 
found:nodes_used_collection_shard1_replica2 in []
   [junit4]   2>        at 
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:294)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269)
   [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 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1056607 INFO  (qtp4044856-10437) [n:127.0.0.1:43631_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard1_replica2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=4
   [junit4]   2> 1056606 INFO  (qtp30714718-10423) [n:127.0.0.1:45345_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=4
   [junit4]   2> 1056607 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard2_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 1056607 INFO  (qtp18293775-10456) [n:127.0.0.1:40505_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=nodes_used_collection_shard2_replica2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=4
   [junit4]   2> 1056606 WARN  
(zkCallback-1521-thread-1-processing-n:127.0.0.1:40505_solr) 
[n:127.0.0.1:40505_solr    ] o.a.s.c.LeaderElector Our node is no longer in 
line to be leader
   [junit4]   2> 1057309 INFO  (qtp16693727-10450) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=nodes_used_collection&action=DELETE&wt=javabin&version=2} status=0 
QTime=712
   [junit4]   2> 1057311 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1058598 INFO  
(OverseerCollectionConfigSetProcessor-98614993375657992-127.0.0.1:45345_solr-n_0000000000)
 [n:127.0.0.1:45345_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> 1063311 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2
   [junit4]   2> 1063314 INFO  
(OverseerStateUpdate-98614993375657992-127.0.0.1:45345_solr-n_0000000000) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 1064320 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.6.1
   [junit4]   2> 1064324 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.s.IndexSchema 
[corewithnocollection] Schema name=minimal
   [junit4]   2> 1064326 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1064326 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.CoreContainer Creating 
SolrCore 'corewithnocollection' using configuration from collection 
corewithnocollection, trusted=true
   [junit4]   2> 1064327 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1064327 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SolrCore 
[[corewithnocollection] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node1/corewithnocollection],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002/]
   [junit4]   2> 1064366 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1064366 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1064366 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1064366 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1064367 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@7e74f3[corewithnocollection] main]
   [junit4]   2> 1064367 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1064368 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1064368 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 1064368 INFO  
(searcherExecutor-4101-thread-1-processing-n:127.0.0.1:41643_solr 
x:corewithnocollection c:corewithnocollection) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SolrCore 
[corewithnocollection] Registered new searcher 
Searcher@7e74f3[corewithnocollection] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1064368 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1577839912371093504
   [junit4]   2> 1064370 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1064371 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1064371 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SyncStrategy Sync 
replicas to https://127.0.0.1:41643/solr/corewithnocollection/
   [junit4]   2> 1064371 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 1064371 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.SyncStrategy 
https://127.0.0.1:41643/solr/corewithnocollection/ has no replicas
   [junit4]   2> 1064371 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1064372 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:41643/solr/corewithnocollection/ shard1
   [junit4]   2> 1064522 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 1064523 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr 
c:corewithnocollection   x:corewithnocollection] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2}
 status=0 QTime=7211
   [junit4]   2> 1064525 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr    ] 
o.a.s.u.TestInjection Inject random core creation delay of 6s
   [junit4]   2> 1070525 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2
   [junit4]   2> 1070526 INFO  
(OverseerStateUpdate-98614993375657992-127.0.0.1:45345_solr-n_0000000000) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 1071532 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.6.1
   [junit4]   2> 1071536 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.s.IndexSchema 
[corewithnocollection2] Schema name=minimal
   [junit4]   2> 1071538 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1071538 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.CoreContainer 
Creating SolrCore 'corewithnocollection2' using configuration from collection 
corewithnocollection2, trusted=true
   [junit4]   2> 1071538 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1071538 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SolrCore 
[[corewithnocollection2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-001/node1/corewithnocollection2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002/]
   [junit4]   2> 1071564 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1071564 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1071565 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1071565 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1071565 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@1ad3503[corewithnocollection2] main]
   [junit4]   2> 1071566 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1071566 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1071566 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 1071567 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1577839919919792128
   [junit4]   2> 1071567 INFO  
(searcherExecutor-4106-thread-1-processing-n:127.0.0.1:41643_solr 
x:corewithnocollection2 c:corewithnocollection2) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SolrCore 
[corewithnocollection2] Registered new searcher 
Searcher@1ad3503[corewithnocollection2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SyncStrategy Sync 
replicas to https://127.0.0.1:41643/solr/corewithnocollection2/
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.SyncStrategy 
https://127.0.0.1:41643/solr/corewithnocollection2/ has no replicas
   [junit4]   2> 1071569 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1071570 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:41643/solr/corewithnocollection2/ shard1
   [junit4]   2> 1071721 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.c.ZkController I am 
the leader, no recovery necessary
   [junit4]   2> 1071722 INFO  (qtp16693727-10447) [n:127.0.0.1:41643_solr 
c:corewithnocollection2   x:corewithnocollection2] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={dataDir=/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_FAF5D9D5E487C171-001/tempDir-002&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=7197
   [junit4]   2> 1071723 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testNoCollectionSpecified
   [junit4]   2> 1071772 INFO  
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting deletePartiallyCreatedCollection
   [junit4]   2> 1071772 INFO  
(TEST-CollectionsAPIDistributedZkTest.deletePartiallyCreatedCollection-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1071774 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=corewithnocollection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1071775 INFO  
(OverseerThreadFactory-4072-thread-4-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1071777 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection
   [junit4]   2> 1071777 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.c.SolrCore [corewithnocollection]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1333a41
   [junit4]   2> 1071778 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection
   [junit4]   2> 1071779 INFO  (qtp16693727-10429) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=corewithnocollection&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1072481 INFO  (qtp16693727-10417) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=corewithnocollection&action=DELETE&wt=javabin&version=2} status=0 
QTime=706
   [junit4]   2> 1072482 INFO  (qtp16693727-10450) [n:127.0.0.1:41643_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=corewithnocollection2&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1072483 INFO  
(OverseerThreadFactory-4072-thread-5-processing-n:127.0.0.1:45345_solr) 
[n:127.0.0.1:45345_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1072483 INFO  
(OverseerCollectionConfigSetProcessor-98614993375657992-127.0.0.1:45345_solr-n_0000000000)
 [n:127.0.0.1:45345_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> 1072485 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection2
   [junit4]   2> 1072485 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.c.SolrCore [corewithnocollection2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1ec1c6c
   [junit4]   2> 1072486 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection2
   [junit4]   2> 1072486 INFO  (qtp16693727-10452) [n:127.0.0.1:41643_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=corewithnocollection2&qt=/admin/cores&deleteDataDir=true&actio

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

bstractConnector Stopped 
ServerConnector@5936a0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1665110 INFO  (jetty-closer-3761-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=28850074
   [junit4]   2> 1665110 INFO  (jetty-closer-3761-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1665110 INFO  (jetty-closer-3761-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 1665110 INFO  (jetty-closer-3761-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 1665111 INFO  (coreCloseExecutor-5864-thread-1) 
[n:127.0.0.1:43475_solr c:cdcr-target s:shard1 r:core_node1 
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]  
CLOSING SolrCore org.apache.solr.core.SolrCore@19f9baf
   [junit4]   2> 1665111 INFO  (coreCloseExecutor-5864-thread-1) 
[n:127.0.0.1:43475_solr c:cdcr-target s:shard1 r:core_node1 
x:cdcr-target_shard1_replica1] o.a.s.h.CdcrRequestHandler Solr core is being 
closed - shutting down CDCR handler @ cdcr-target:shard1
   [junit4]   2> 1665112 INFO  (coreCloseExecutor-5864-thread-1) 
[n:127.0.0.1:43475_solr c:cdcr-target s:shard1 r:core_node1 
x:cdcr-target_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.cdcr-target.shard1.replica1
   [junit4]   2> 1665112 INFO  
(zkCallback-3767-thread-2-processing-n:127.0.0.1:43475_solr) 
[n:127.0.0.1:43475_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/cdcr-target/state.json] for collection [cdcr-target] has 
occurred - updating... (live nodes size: [1])
   [junit4]   2> 1665112 INFO  (jetty-closer-3761-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=98615025226285059-127.0.0.1:43475_solr-n_0000000000) closing
   [junit4]   2> 1665112 INFO  
(OverseerStateUpdate-98615025226285059-127.0.0.1:43475_solr-n_0000000000) 
[n:127.0.0.1:43475_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:43475_solr
   [junit4]   2> 1665113 WARN  
(zkCallback-3767-thread-2-processing-n:127.0.0.1:43475_solr) 
[n:127.0.0.1:43475_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1665113 INFO  (jetty-closer-3761-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@bf3903{/solr,null,UNAVAILABLE}
   [junit4]   2> 1665113 ERROR 
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[FAF5D9D5E487C171])
 [    ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so 
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1665113 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37113 37113
   [junit4]   2> 1670903 INFO  (Thread-2392) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37113 37113
   [junit4]   2> 1670904 WARN  (Thread-2392) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/aliases.json
   [junit4]   2>        3       /solr/clusterprops.json
   [junit4]   2>        2       /solr/configs/cdcr-target/managed-schema
   [junit4]   2>        2       
/solr/collections/cdcr-target/leaders/shard1/leader
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/collections/cdcr-target/cdcr/state/process
   [junit4]   2>        3       /solr/clusterstate.json
   [junit4]   2>        2       /solr/collections/cdcr-target/cdcr/state/buffer
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/collections
   [junit4]   2> 
   [junit4]   2> 1670904 INFO  
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[FAF5D9D5E487C171])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testBootstrapWithContinousIndexingOnSourceCluster
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CdcrBootstrapTest 
-Dtests.method=testBootstrapWithContinousIndexingOnSourceCluster 
-Dtests.seed=FAF5D9D5E487C171 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=th -Dtests.timezone=Pacific/Majuro -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE  138s J0 | 
CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Document mismatch on 
target after sync expected:<2000> but was:<1901>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([FAF5D9D5E487C171:2EB0928C03D1728A]:0)
   [junit4]    >        at 
org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster(CdcrBootstrapTest.java:309)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1670906 INFO  
(SUITE-CdcrBootstrapTest-seed#[FAF5D9D5E487C171]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.6-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_FAF5D9D5E487C171-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{_version_=PostingsFormat(name=MockRandom), xyz=Lucene50(blocksize=128), 
id=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1610, 
maxMBSortInHeap=6.478322519195284, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=th, 
timezone=Pacific/Majuro
   [junit4]   2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 
1.8.0_144 (32-bit)/cpus=8,threads=1,free=185372952,total=406847488
   [junit4]   2> NOTE: All tests run in this JVM: [TestPivotHelperCode, 
FastVectorHighlighterTest, TestClassicSimilarityFactory, 
TestStressUserVersions, TestFieldCacheVsDocValues, ScriptEngineTest, 
DocumentAnalysisRequestHandlerTest, CollectionsAPIAsyncDistributedZkTest, 
TestHighFrequencyDictionaryFactory, TestManagedSchema, 
ShufflingReplicaListTransformerTest, TestLocalFSCloudBackupRestore, 
TestSolrCloudWithSecureImpersonation, ClusterStateTest, 
TestMinMaxOnMultiValuedField, BlobRepositoryCloudTest, TestClassNameShortening, 
SolrGraphiteReporterTest, TestAddFieldRealTimeGet, TestInPlaceUpdatesDistrib, 
TestMultiWordSynonyms, TestSolrDeletionPolicy2, HdfsUnloadDistributedZkTest, 
TestBadConfig, SuggestComponentTest, TestSubQueryTransformerCrossCore, 
TestSolrCoreParser, BasicDistributedZkTest, TestCodecSupport, 
BaseCdcrDistributedZkTest, AlternateDirectoryTest, TestComponentsName, 
TestDocSet, TestRandomDVFaceting, AtomicUpdatesTest, 
TestUniqueKeyFieldResource, TestDownShardTolerantSearch, RequiredFieldsTest, 
ExplicitHLLTest, MissingSegmentRecoveryTest, BasicAuthStandaloneTest, 
TestRandomCollapseQParserPlugin, TestJsonRequest, TestRandomFlRTGCloud, 
UniqFieldsUpdateProcessorFactoryTest, TestCloudSchemaless, 
StandardRequestHandlerTest, LeaderElectionContextKeyTest, 
DataDrivenBlockJoinTest, DistributedFacetExistsSmallTest, 
LeaderElectionIntegrationTest, TestSizeLimitedDistributedMap, TestInitQParser, 
AnalyticsQueryTest, ZkNodePropsTest, PathHierarchyTokenizerFactoryTest, 
TestLazyCores, TestCloudRecovery, TestCryptoKeys, TestOmitPositions, 
AliasIntegrationTest, TestPayloadCheckQParserPlugin, TestSolrCloudSnapshots, 
TestDynamicFieldResource, TestFilteredDocIdSet, MBeansHandlerTest, 
LeaderInitiatedRecoveryOnCommitTest, TestBlendedInfixSuggestions, 
JvmMetricsTest, SecurityConfHandlerTest, TestFieldTypeCollectionResource, 
CollectionReloadTest, DocValuesNotIndexedTest, TestSolr4Spatial, 
TestCloudJSONFacetJoinDomain, TestStressLucene, TestFoldingMultitermQuery, 
TestManagedResourceStorage, TestBackupRepositoryFactory, TestRangeQuery, 
QueryEqualityTest, SolrCLIZkUtilsTest, TestPathTrie, DateRangeFieldTest, 
ReturnFieldsTest, TestXmlQParserPlugin, TestBulkSchemaConcurrent, 
DistribJoinFromCollectionTest, BigEndianAscendingWordDeserializerTest, 
TestLeaderElectionWithEmptyReplica, VersionInfoTest, TestJmxIntegration, 
BasicZkTest, ConfigSetsAPITest, BlockJoinFacetSimpleTest, 
SolrSlf4jReporterTest, HdfsSyncSliceTest, TestStressRecovery, 
TestIBSimilarityFactory, DateFieldTest, TestDocumentBuilder, 
TestPseudoReturnFields, TestCustomStream, HighlighterConfigTest, 
TestStressLiveNodes, BufferStoreTest, TestNestedDocsSort, TestCrossCoreJoin, 
TestJoin, InfoHandlerTest, BadCopyFieldTest, NumericFieldsTest, 
TestDistributedSearch, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, 
TestRandomFaceting, ZkSolrClientTest, TestFaceting, TestHashPartitioner, 
TermVectorComponentDistributedTest, TestStressVersions, SimpleFacetsTest, 
StatsComponentTest, TestGroupingSearch, ConvertedLegacyTest, TestFiltering, 
ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, 
DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, 
SolrIndexSplitterTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, 
SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, 
CoreAdminHandlerTest, SolrRequestParserTest, SuggesterTSTTest, SuggesterTest, 
TestTrie, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, 
WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, 
TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, 
DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, 
TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, 
IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, TestQueryTypes, 
FileBasedSpellCheckerTest, MoreLikeThisHandlerTest, JsonLoaderTest, 
PingRequestHandlerTest, TestUtils, CircularListTest, 
DistributedMLTComponentTest, SolrTestCaseJ4Test, 
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, 
TestSimpleTrackingShardHandler, ActionThrottleTest, AssignTest, 
CdcrBootstrapTest]
   [junit4] Completed [506/713 (2!)] on J0 in 195.09s, 3 tests, 1 failure <<< 
FAILURES!

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

Reply via email to