Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/723/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

2 tests failed.
FAILED:  
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter

Error Message:
Collection not found: withShardField

Stack Trace:
org.apache.solr.common.SolrException: Collection not found: withShardField
        at 
__randomizedtesting.SeedInfo.seed([E473512797367CD7:B123B9B53BCFB327]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1379)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1072)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1042)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:232)
        at 
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:141)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.DistribJoinFromCollectionTest

Error Message:
Could not find collection : to_2x2

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : to_2x2
        at __randomizedtesting.SeedInfo.seed([E473512797367CD7]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
        at 
org.apache.solr.cloud.DistribJoinFromCollectionTest.setupCluster(DistribJoinFromCollectionTest.java:94)
        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$6.evaluate(RandomizedRunner.java:847)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 12510 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/init-core-data-001
   [junit4]   2> 2468290 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 2468294 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 2468294 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001
   [junit4]   2> 2468294 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2468295 INFO  (Thread-3938) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2468295 INFO  (Thread-3938) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2468398 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:58578
   [junit4]   2> 2468428 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2468428 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2468429 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2468429 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2468436 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6d314ede{/solr,null,AVAILABLE}
   [junit4]   2> 2468437 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@49043507{/solr,null,AVAILABLE}
   [junit4]   2> 2468438 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@50e67af4{/solr,null,AVAILABLE}
   [junit4]   2> 2468438 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6787b0b6{HTTP/1.1,[http/1.1]}{127.0.0.1:58582}
   [junit4]   2> 2468438 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.e.j.s.Server Started @2473901ms
   [junit4]   2> 2468438 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58582}
   [junit4]   2> 2468439 ERROR (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2468439 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 2468439 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2468439 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2468439 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T01:36:52.824Z
   [junit4]   2> 2468440 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@26ef627{HTTP/1.1,[http/1.1]}{127.0.0.1:58581}
   [junit4]   2> 2468440 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@36a81c74{/solr,null,AVAILABLE}
   [junit4]   2> 2468440 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.e.j.s.Server Started @2473903ms
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58581}
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1362dba2{HTTP/1.1,[http/1.1]}{127.0.0.1:58583}
   [junit4]   2> 2468441 ERROR (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3679c853{HTTP/1.1,[http/1.1]}{127.0.0.1:58580}
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.e.j.s.Server Started @2473903ms
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58580}
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.e.j.s.Server Started @2473903ms
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 2468441 ERROR (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2468442 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2468442 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2468442 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T01:36:52.827Z
   [junit4]   2> 2468443 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 2468441 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=58583}
   [junit4]   2> 2468445 ERROR (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2468443 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2468445 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2468445 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T01:36:52.830Z
   [junit4]   2> 2468446 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 2468446 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2468446 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2468446 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T01:36:52.831Z
   [junit4]   2> 2468452 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2468452 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2468453 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2468453 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2468469 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2468470 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2468470 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2468471 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2468472 INFO  (jetty-launcher-4435-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58578/solr
   [junit4]   2> 2468473 INFO  (jetty-launcher-4435-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58578/solr
   [junit4]   2> 2468474 INFO  (jetty-launcher-4435-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58578/solr
   [junit4]   2> 2468475 INFO  (jetty-launcher-4435-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58578/solr
   [junit4]   2> 2468534 INFO  (jetty-launcher-4435-thread-1) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2468534 INFO  (jetty-launcher-4435-thread-2) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2468535 INFO  (jetty-launcher-4435-thread-3) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2468535 INFO  (jetty-launcher-4435-thread-4) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2468536 INFO  (jetty-launcher-4435-thread-1) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:58583_solr
   [junit4]   2> 2468537 INFO  (jetty-launcher-4435-thread-2) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58580_solr
   [junit4]   2> 2468538 INFO  (jetty-launcher-4435-thread-3) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58582_solr
   [junit4]   2> 2468538 INFO  (jetty-launcher-4435-thread-4) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58581_solr
   [junit4]   2> 2468538 INFO  (jetty-launcher-4435-thread-1) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.Overseer Overseer 
(id=97528015269593099-127.0.0.1:58583_solr-n_0000000000) starting
   [junit4]   2> 2468542 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2468542 INFO  
(zkCallback-4456-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2468543 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2468544 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2468547 INFO  
(zkCallback-4456-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2468547 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2468548 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2468548 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2468573 INFO  (jetty-launcher-4435-thread-1) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:58583_solr
   [junit4]   2> 2468578 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2468579 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2468579 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2468580 INFO  
(zkCallback-4456-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2468785 INFO  (jetty-launcher-4435-thread-1) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node1/.
   [junit4]   2> 2468785 INFO  (jetty-launcher-4435-thread-4) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node4/.
   [junit4]   2> 2468787 INFO  (jetty-launcher-4435-thread-3) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node3/.
   [junit4]   2> 2468788 INFO  (jetty-launcher-4435-thread-2) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node2/.
   [junit4]   2> 2472650 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2472653 INFO  
(SUITE-CustomCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58578/solr ready
   [junit4]   2> 2472708 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[E473512797367CD7]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForHashRouter
   [junit4]   2> 2472710 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[E473512797367CD7]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2472720 INFO  (qtp2036363255-20682) [n:127.0.0.1:58582_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&router.field=shard_s&name=routeFieldColl&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2472726 INFO  
(OverseerThreadFactory-9531-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
routeFieldColl
   [junit4]   2> 2472842 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2472842 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2
   [junit4]   2> 2472846 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2
   [junit4]   2> 2472849 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2
   [junit4]   2> 2472849 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2472850 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2
   [junit4]   2> 2472849 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2
   [junit4]   2> 2472851 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2
   [junit4]   2> 2473004 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2473004 INFO  
(zkCallback-4456-thread-3-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2473004 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2473004 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2473894 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473895 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473897 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473894 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473912 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473921 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2473922 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2473923 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2473931 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2473897 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473944 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473947 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 2473959 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2473959 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2473959 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2473961 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2474034 WARN  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474066 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474066 WARN  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474067 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474068 WARN  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474069 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474065 WARN  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474071 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474057 WARN  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474072 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474072 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard4_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node4/routeFieldColl_shard4_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node4/./routeFieldColl_shard4_replica1/data/]
   [junit4]   2> 2474053 WARN  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474073 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474070 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474075 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474075 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474075 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard1_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node3/routeFieldColl_shard1_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node3/./routeFieldColl_shard1_replica2/data/]
   [junit4]   2> 2474075 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard2_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node4/routeFieldColl_shard2_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node4/./routeFieldColl_shard2_replica1/data/]
   [junit4]   2> 2474075 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard2_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node1/routeFieldColl_shard2_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node1/./routeFieldColl_shard2_replica2/data/]
   [junit4]   2> 2474073 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474076 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474077 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard4_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node1/routeFieldColl_shard4_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node1/./routeFieldColl_shard4_replica2/data/]
   [junit4]   2> 2474079 WARN  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474081 WARN  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 2474082 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474082 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2474084 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474084 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474085 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard3_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node2/routeFieldColl_shard3_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node2/./routeFieldColl_shard3_replica1/data/]
   [junit4]   2> 2474085 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard1_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node2/routeFieldColl_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node2/./routeFieldColl_shard1_replica1/data/]
   [junit4]   2> 2474085 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 2474085 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard3_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node3/routeFieldColl_shard3_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_E473512797367CD7-001/tempDir-001/node3/./routeFieldColl_shard3_replica2/data/]
   [junit4]   2> 2474273 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474273 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474281 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474282 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474287 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474288 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474289 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474289 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474290 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474290 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474291 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474291 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474295 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474296 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474298 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474298 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474300 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474300 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474300 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474301 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474304 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@459c8531[routeFieldColl_shard3_replica1] main]
   [junit4]   2> 2474304 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474304 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474307 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474308 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474308 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474308 INFO  
(searcherExecutor-9545-thread-1-processing-n:127.0.0.1:58580_solr 
x:routeFieldColl_shard3_replica1 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:58580_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica1] Registered new searcher 
Searcher@459c8531[routeFieldColl_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474309 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250616479744
   [junit4]   2> 2474309 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474309 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474310 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2474310 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2474312 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474312 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474313 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7b3c958b[routeFieldColl_shard4_replica1] main]
   [junit4]   2> 2474314 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474314 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474314 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@9b3bf[routeFieldColl_shard4_replica2] main]
   [junit4]   2> 2474315 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474316 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474316 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474316 INFO  
(searcherExecutor-9540-thread-1-processing-n:127.0.0.1:58581_solr 
x:routeFieldColl_shard4_replica1 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:58581_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica1] Registered new searcher 
Searcher@7b3c958b[routeFieldColl_shard4_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474316 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250623819776
   [junit4]   2> 2474317 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474318 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474318 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474318 INFO  
(searcherExecutor-9544-thread-1-processing-n:127.0.0.1:58583_solr 
x:routeFieldColl_shard4_replica2 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:58583_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica2] Registered new searcher 
Searcher@9b3bf[routeFieldColl_shard4_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474319 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250626965504
   [junit4]   2> 2474323 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2474328 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2474329 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2474329 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:58581/solr/routeFieldColl_shard4_replica1/
   [junit4]   2> 2474327 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard3: total=2 found=1 timeoutin=9998ms
   [junit4]   2> 2474329 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2474331 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica1 url=http://127.0.0.1:58581/solr 
START replicas=[http://127.0.0.1:58583/solr/routeFieldColl_shard4_replica2/] 
nUpdates=100
   [junit4]   2> 2474336 INFO  (qtp1262982077-20696) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4 r:core_node8 x:routeFieldColl_shard4_replica2] 
o.a.s.c.S.Request [routeFieldColl_shard4_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2474336 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@31967091[routeFieldColl_shard2_replica1] main]
   [junit4]   2> 2474337 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica1 url=http://127.0.0.1:58581/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2474338 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2474338 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_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> 2474338 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2474341 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474341 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@76057402[routeFieldColl_shard1_replica2] main]
   [junit4]   2> 2474342 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474342 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474342 INFO  
(searcherExecutor-9542-thread-1-processing-n:127.0.0.1:58581_solr 
x:routeFieldColl_shard2_replica1 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:58581_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica1] Registered new searcher 
Searcher@31967091[routeFieldColl_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474342 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1021b8f8[routeFieldColl_shard1_replica1] main]
   [junit4]   2> 2474342 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250651082752
   [junit4]   2> 2474344 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474345 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474345 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474346 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250655277056
   [junit4]   2> 2474346 INFO  
(searcherExecutor-9546-thread-1-processing-n:127.0.0.1:58580_solr 
x:routeFieldColl_shard1_replica1 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:58580_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica1] Registered new searcher 
Searcher@1021b8f8[routeFieldColl_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474348 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1b163e42[routeFieldColl_shard3_replica2] main]
   [junit4]   2> 2474352 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:58581/solr/routeFieldColl_shard4_replica1/ shard4
   [junit4]   2> 2474354 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474355 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474356 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474356 INFO  
(searcherExecutor-9547-thread-1-processing-n:127.0.0.1:58582_solr 
x:routeFieldColl_shard3_replica2 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:58582_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica2] Registered new searcher 
Searcher@1b163e42[routeFieldColl_shard3_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474356 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250665762816
   [junit4]   2> 2474357 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_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> 2474360 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474361 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@331a01f0[routeFieldColl_shard2_replica2] main]
   [junit4]   2> 2474363 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2474363 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474364 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474364 INFO  
(searcherExecutor-9541-thread-1-processing-n:127.0.0.1:58582_solr 
x:routeFieldColl_shard1_replica2 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:58582_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica2] Registered new searcher 
Searcher@76057402[routeFieldColl_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474365 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250675200000
   [junit4]   2> 2474366 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2474367 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2474367 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2474367 INFO  
(searcherExecutor-9543-thread-1-processing-n:127.0.0.1:58583_solr 
x:routeFieldColl_shard2_replica2 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:58583_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica2] Registered new searcher 
Searcher@331a01f0[routeFieldColl_shard2_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2474367 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1560448250677297152
   [junit4]   2> 2474477 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474477 INFO  
(zkCallback-4456-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474477 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474477 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474526 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2474533 INFO  (qtp1695121872-20675) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2}
 status=0 QTime=1683
   [junit4]   2> 2474645 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474645 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474645 INFO  
(zkCallback-4456-thread-3-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474645 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474844 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2474844 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2474844 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:58580/solr/routeFieldColl_shard3_replica1/
   [junit4]   2> 2474844 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica1 url=http://127.0.0.1:58580/solr 
START replicas=[http://127.0.0.1:58582/solr/routeFieldColl_shard3_replica2/] 
nUpdates=100
   [junit4]   2> 2474845 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica1 url=http://127.0.0.1:58580/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2474849 INFO  (qtp2036363255-20680) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3 r:core_node1 x:routeFieldColl_shard3_replica2] 
o.a.s.c.S.Request [routeFieldColl_shard3_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2474850 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2474850 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_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> 2474850 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2474858 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2474858 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2474858 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:58581/solr/routeFieldColl_shard2_replica1/
   [junit4]   2> 2474858 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica1 url=http://127.0.0.1:58581/solr 
START replicas=[http://127.0.0.1:58583/solr/routeFieldColl_shard2_replica2/] 
nUpdates=100
   [junit4]   2> 2474859 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica1 url=http://127.0.0.1:58581/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2474859 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:58580/solr/routeFieldColl_shard3_replica1/ shard3
   [junit4]   2> 2474861 INFO  (qtp1262982077-20696) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.c.S.Request [routeFieldColl_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> 2474861 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2474862 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_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> 2474862 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2474865 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2474865 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2474865 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:58580/solr/routeFieldColl_shard1_replica1/
   [junit4]   2> 2474866 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard1_replica1 url=http://127.0.0.1:58580/solr 
START replicas=[http://127.0.0.1:58582/solr/routeFieldColl_shard1_replica2/] 
nUpdates=100
   [junit4]   2> 2474866 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard1_replica1 url=http://127.0.0.1:58580/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2474868 INFO  (qtp2036363255-20687) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1 r:core_node2 x:routeFieldColl_shard1_replica2] 
o.a.s.c.S.Request [routeFieldColl_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> 2474869 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2474869 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_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> 2474869 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2474870 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:58581/solr/routeFieldColl_shard2_replica1/ shard2
   [junit4]   2> 2474877 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:58580/solr/routeFieldColl_shard1_replica1/ shard1
   [junit4]   2> 2474994 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474994 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474994 INFO  
(zkCallback-4456-thread-3-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2474994 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475041 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2475042 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2475042 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2475047 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2}
 status=0 QTime=2197
   [junit4]   2> 2475047 INFO  (qtp1695121872-20673) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2}
 status=0 QTime=2198
   [junit4]   2> 2475048 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2198
   [junit4]   2> 2475170 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475170 INFO  
(zkCallback-4456-thread-1-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475170 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475170 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475336 INFO  (qtp1262982077-20694) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2}
 status=0 QTime=2485
   [junit4]   2> 2475381 INFO  (qtp2036363255-20684) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2539
   [junit4]   2> 2475381 INFO  (qtp1262982077-20688) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2}
 status=0 QTime=2535
   [junit4]   2> 2475382 INFO  (qtp2036363255-20685) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2}
 status=0 QTime=2540
   [junit4]   2> 2475387 INFO  (qtp2036363255-20682) [n:127.0.0.1:58582_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> 2475503 INFO  
(zkCallback-4455-thread-1-processing-n:127.0.0.1:58582_solr) 
[n:127.0.0.1:58582_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475503 INFO  
(zkCallback-4457-thread-1-processing-n:127.0.0.1:58580_solr) 
[n:127.0.0.1:58580_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475503 INFO  
(zkCallback-4456-thread-3-processing-n:127.0.0.1:58583_solr) 
[n:127.0.0.1:58583_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2475503 INFO  
(zkCallback-4454-thread-1-processing-n:127.0.0.1:58581_solr) 
[n:127.0.0.1:58581_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/routeFieldColl/state.json] for collection [routeFieldColl] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 2476390 INFO  (qtp2036363255-20682) [n:127.0.0.1:58582_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&router.field=shard_s&name=routeFieldColl&action=CREATE&numShards=4&wt=javabin&version=2}
 status=0 QTime=3670
   [junit4]   2> 2476402 INFO  (qtp2036363255-20686) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1 r:core_node2 x:routeFieldColl_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard1_replica2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58580/solr/routeFieldColl_shard1_replica1/&wt=javabin&version=2}{add=[8
 (1560448252802760704)]} 0 1
   [junit4]   2> 2476403 INFO  (qtp2036363255-20689) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3 r:core_node1 x:routeFieldColl_shard3_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58580/solr/routeFieldColl_shard3_replica1/&wt=javabin&version=2}{add=[6
 (1560448252802760704), 7 (1560448252805906432)]} 0 4
   [junit4]   2> 2476403 INFO  (qtp1297529802-20668) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard1_replica1]  
webapp=/solr path=/update 
params={_stateVer_=routeFieldColl:6&wt=javabin&version=2}{add=[8 
(1560448252802760704)]} 0 9
   [junit4]   2> 2476403 INFO  (qtp1297529802-20666) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica1]  
webapp=/solr path=/update 
params={_stateVer_=routeFieldColl:6&wt=javabin&version=2}{add=[6 
(1560448252802760704), 7 (1560448252805906432)]} 0 9
   [junit4]   2> 2476409 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476409 INFO  (qtp1695121872-20676) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2 r:core_node3 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476409 INFO  (qtp1695121872-20676) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2 r:core_node3 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2476409 INFO  (qtp1695121872-20676) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2 r:core_node3 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2476409 INFO  (qtp1297529802-20664) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@660d42ba
   [junit4]   2> 2476410 INFO  (qtp2036363255-20680) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1 r:core_node2 x:routeFieldColl_shard1_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476410 INFO  (qtp2036363255-20680) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard1 r:core_node2 x:routeFieldColl_shard1_replica2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@fa3a80a
   [junit4]   2> 2476410 INFO  (qtp1695121872-20676) [n:127.0.0.1:58581_solr 
c:routeFieldColl s:shard2 r:core_node3 x:routeFieldColl_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard2_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:58580/solr/routeFieldColl_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2476413 INFO  (qtp2036363255-20687) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3 r:core_node1 x:routeFieldColl_shard3_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476413 INFO  (qtp1262982077-20697) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476413 INFO  (qtp1262982077-20697) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2476413 INFO  (qtp1262982077-20697) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2476414 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2476414 INFO  (qtp1297529802-20665) [n:127.0.0.1:58580_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4dcfe15e
   [junit4]   2> 2476413 INFO  (qtp2036363255-20687) [n:127.0.0.1:58582_solr 
c:routeFieldColl s:shard3 r:core_node1 x:routeFieldColl_shard3_replica2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6814a91b
   [junit4]   2> 2476414 INFO  (qtp1262982077-20697) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard2_replica2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:58580/solr/routeFieldColl_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2476415 INFO  (qtp1262982077-20698) [n:127.0.0.1:58583_solr 
c:routeFieldColl s:shard4 r

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

.0.0.1:58787 58787
   [junit4]   2> 2590106 INFO  (Thread-3999) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:58787 58787
   [junit4]   2> 2593664 WARN  (Thread-3999) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2>        6       /solr/clusterprops.json
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        3       /solr/configs/solrCloudCollectionConfig
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/clusterstate.json
   [junit4]   2>        4       /solr/collections/to_2x2/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        37      /solr/overseer/collection-queue-work
   [junit4]   2>        19      /solr/overseer/queue
   [junit4]   2>        6       /solr/live_nodes
   [junit4]   2>        6       /solr/collections
   [junit4]   2>        4       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 2593665 INFO  
(SUITE-DistribJoinFromCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2593665 INFO  
(SUITE-DistribJoinFromCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for all SolrIndexSearchers to be released
   [junit4]   2> 2593665 INFO  
(SUITE-DistribJoinFromCollectionTest-seed#[E473512797367CD7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62), 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-EG, 
timezone=Asia/Irkutsk
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=352939000,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [ShardRoutingCustomTest, 
TestSystemIdResolver, TestIndexingPerformance, 
DistribDocExpirationUpdateProcessorTest, SolrCloudExampleTest, 
DistributedFacetExistsSmallTest, TestReload, TestReqParamsAPI, AssignTest, 
MinimalSchemaTest, TestTolerantUpdateProcessorCloud, 
DistributedFacetPivotLongTailTest, TestSolrFieldCacheMBean, ReturnFieldsTest, 
SignatureUpdateProcessorFactoryTest, HdfsRecoveryZkTest, SolrIndexMetricsTest, 
TestSimpleQParserPlugin, CacheHeaderTest, CircularListTest, 
LukeRequestHandlerTest, TestSimpleTrackingShardHandler, 
TestBulkSchemaConcurrent, OpenCloseCoreStressTest, DocValuesNotIndexedTest, 
BasicDistributedZk2Test, TestPathTrie, TestLocalFSCloudBackupRestore, 
BlockJoinFacetDistribTest, SortByFunctionTest, TestSolrConfigHandler, 
ReplicationFactorTest, BadIndexSchemaTest, SuggesterTest, 
TestHdfsBackupRestoreCore, UUIDUpdateProcessorFallbackTest, 
SaslZkACLProviderTest, DistributedQueueTest, CurrencyFieldOpenExchangeTest, 
TestStressUserVersions, TestDistributedGrouping, 
FieldMutatingUpdateProcessorTest, ZkSolrClientTest, TestDistribIDF, 
TestPKIAuthenticationPlugin, TestFuzzyAnalyzedSuggestions, TestFieldResource, 
URLClassifyProcessorTest, LeaderFailoverAfterPartitionTest, 
TestMiniSolrCloudCluster, SliceStateTest, ParsingFieldUpdateProcessorsTest, 
BasicZkTest, BlockCacheTest, TestFieldCacheSortRandom, TestWriterPerf, 
TestReloadDeadlock, BasicAuthIntegrationTest, TestRealTimeGet, TestSearchPerf, 
LeaderInitiatedRecoveryOnCommitTest, RegexBoostProcessorTest, BufferStoreTest, 
TestJettySolrRunner, TestRangeQuery, CdcrReplicationDistributedZkTest, 
TestFoldingMultitermQuery, TestExactStatsCache, TestBadConfig, 
DistributedFacetPivotSmallTest, TestInPlaceUpdatesDistrib, 
BigEndianAscendingWordDeserializerTest, 
TestSolrQueryParserDefaultOperatorResource, HdfsChaosMonkeySafeLeaderTest, 
TestUninvertingReader, TestMiniSolrCloudClusterSSL, TestExceedMaxTermLength, 
TestLuceneMatchVersion, TlogReplayBufferedWhileIndexingTest, TestDocSet, 
TestConfigSetsAPIZkFailure, TestCloudInspectUtil, TestManagedResource, 
SolrJmxReporterTest, HdfsTlogReplayBufferedWhileIndexingTest, 
SolrIndexConfigTest, OverseerStatusTest, ClassificationUpdateProcessorTest, 
SegmentsInfoRequestHandlerTest, TestDistributedMissingSort, 
SolrCmdDistributorTest, FullHLLTest, SharedFSAutoReplicaFailoverTest, 
TestSolrConfigHandlerCloud, TestSolr4Spatial2, TestBlendedInfixSuggestions, 
DefaultValueUpdateProcessorTest, TestOmitPositions, 
CoreAdminCreateDiscoverTest, TestJsonFacetRefinement, TestClusterProperties, 
RAMDirectoryFactoryTest, RemoteQueryErrorTest, 
TestSubQueryTransformerCrossCore, HttpSolrCallGetCoreTest, 
OverriddenZkACLAndCredentialsProvidersTest, TestCollationFieldDocValues, 
TestSuggestSpellingConverter, TestJmxMonitoredMap, 
TestReversedWildcardFilterFactory, MigrateRouteKeyTest, 
TestCloudPseudoReturnFields, TestRTimerTree, TestFieldTypeResource, 
TestHdfsCloudBackupRestore, TestFastWriter, TestConfigSetsAPI, 
TestScoreJoinQPNoScore, TestConfig, TestJoin, ClusterStateTest, 
TestStressInPlaceUpdates, CachingDirectoryFactoryTest, TestUtils, 
TestQueryUtils, DistributedSuggestComponentTest, OverseerModifyCollectionTest, 
TestFiltering, DistributedDebugComponentTest, CSVRequestHandlerTest, 
TestCollapseQParserPlugin, ConnectionManagerTest, CoreAdminHandlerTest, 
DebugComponentTest, TestBlobHandler, MetricUtilsTest, 
ShufflingReplicaListTransformerTest, ConcurrentDeleteAndCreateCollectionTest, 
TestTestInjection, SuggesterWFSTTest, AnalyticsQueryTest, 
TestAtomicUpdateErrorCases, PreAnalyzedFieldManagedSchemaCloudTest, 
TestNumericTerms32, TestXmlQParser, TestMinMaxOnMultiValuedField, 
TestClassNameShortening, FullSolrCloudDistribCmdsTest, JsonValidatorTest, 
DirectUpdateHandlerOptimizeTest, TestCodecSupport, TestRequestForwarding, 
TestLMDirichletSimilarityFactory, DirectoryFactoryTest, TestSchemaResource, 
DateRangeFieldTest, CopyFieldTest, NoCacheHeaderTest, 
ExternalFileFieldSortTest, BinaryUpdateRequestHandlerTest, TestPointFields, 
TestDistributedStatsComponentCardinality, TestMissingGroups, 
CloudExitableDirectoryReaderTest, JsonLoaderTest, SpellCheckCollatorTest, 
SuggestComponentContextFilterQueryTest, TestSolrDeletionPolicy2, 
JavabinLoaderTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, 
CollectionsAPIDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, 
ShardRoutingTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, 
TestRandomDVFaceting, AutoCommitTest, TestRecovery, 
TermVectorComponentDistributedTest, ZkControllerTest, HardAutoCommitTest, 
DistributedTermsComponentTest, TestSolr4Spatial, SolrCoreTest, 
StatsComponentTest, SpellCheckComponentTest, PeerSyncTest, ConvertedLegacyTest, 
TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, 
DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest, 
SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest, 
SolrIndexSplitterTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, 
SolrRequestParserTest, SuggesterTSTTest, TestTrie, SpatialFilterTest, 
WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, 
TestWordDelimiterFilterFactory, QueryEqualityTest, DocValuesMultiTest, 
DistanceFunctionTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, 
IndexBasedSpellCheckerTest, TestSurroundQueryParser, 
StandardRequestHandlerTest, DirectSolrSpellCheckerTest, TestQueryTypes, 
FileBasedSpellCheckerTest, RequestHandlersTest, DocumentBuilderTest, 
TestValueSourceCache, PathHierarchyTokenizerFactoryTest, 
TermVectorComponentTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, 
TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, 
LoggingHandlerTest, SolrPluginUtilsTest, IndexSchemaTest, TestCollationField, 
UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, 
MBeansHandlerTest, QueryParsingTest, TestAnalyzedSuggestions, 
TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, 
PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, 
TestBinaryResponseWriter, HighlighterConfigTest, TestQuerySenderListener, 
UpdateParamsTest, TestQuerySenderNoQuery, ResponseLogComponentTest, 
TestMergePolicyConfig, MultiTermTest, SampleTest, TestBinaryField, 
NumericFieldsTest, TestElisionMultitermQuery, TestSolrCoreProperties, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, 
NotRequiredUniqueKeyTest, TestPhraseSuggestions, SpellPossibilityIteratorTest, 
SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, 
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, 
TestIBSimilarityFactory, TestBM25SimilarityFactory, ResourceLoaderTest, 
ScriptEngineTest, OpenExchangeRatesOrgProviderTest, DateMathParserTest, 
PreAnalyzedFieldTest, PrimUtilsTest, SpellingQueryConverterTest, DOMUtilTest, 
TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, 
UUIDFieldTest, FileUtilsTest, DistributedMLTComponentTest, TestRTGBase, 
CursorPagingTest, DistributedIntervalFacetingTest, ActionThrottleTest, 
BaseCdcrDistributedZkTest, CdcrVersionReplicationTest, 
CreateCollectionCleanupTest, CustomCollectionTest, DeleteStatusTest, 
DistribJoinFromCollectionTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=DistribJoinFromCollectionTest -Dtests.seed=E473512797367CD7 
-Dtests.slow=true -Dtests.locale=ar-EG -Dtests.timezone=Asia/Irkutsk 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | DistribJoinFromCollectionTest (suite) <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
find collection : to_2x2
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E473512797367CD7]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
   [junit4]    >        at 
org.apache.solr.cloud.DistribJoinFromCollectionTest.setupCluster(DistribJoinFromCollectionTest.java:94)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [496/696 (2!)] on J1 in 47.42s, 0 tests, 1 error <<< 
FAILURES!

[...truncated 63341 lines...]

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

Reply via email to