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

1 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([F53CABC0B3C6DE6F:A06C43521F3F119F]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1376)
        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)




Build Log:
[...truncated 11764 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/init-core-data-001
   [junit4]   2> 1475155 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1475160 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-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> 1475161 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001
   [junit4]   2> 1475162 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1475162 INFO  (Thread-2361) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1475162 INFO  (Thread-2361) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1475263 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:59183
   [junit4]   2> 1475293 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1475293 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1475293 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1475293 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1475298 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5cc8365a{/solr,null,AVAILABLE}
   [junit4]   2> 1475300 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@665ca0f9{/solr,null,AVAILABLE}
   [junit4]   2> 1475301 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@40547320{/solr,null,AVAILABLE}
   [junit4]   2> 1475302 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1a7b56b4{/solr,null,AVAILABLE}
   [junit4]   2> 1475308 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3f55b6a4{HTTP/1.1,[http/1.1]}{127.0.0.1:59188}
   [junit4]   2> 1475308 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@20ffb419{HTTP/1.1,[http/1.1]}{127.0.0.1:59189}
   [junit4]   2> 1475308 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.e.j.s.Server Started @1482294ms
   [junit4]   2> 1475308 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.e.j.s.Server Started @1482294ms
   [junit4]   2> 1475309 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=59188}
   [junit4]   2> 1475309 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4d1affd0{HTTP/1.1,[http/1.1]}{127.0.0.1:59190}
   [junit4]   2> 1475309 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.e.j.s.Server Started @1482295ms
   [junit4]   2> 1475309 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=59190}
   [junit4]   2> 1475309 ERROR (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1475309 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=59189}
   [junit4]   2> 1475310 ERROR (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1475309 ERROR (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@740539e0{HTTP/1.1,[http/1.1]}{127.0.0.1:59191}
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.e.j.s.Server Started @1482296ms
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-17T02:19:11.785Z
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=59191}
   [junit4]   2> 1475310 ERROR (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1475310 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1475311 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-17T02:19:11.786Z
   [junit4]   2> 1475312 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1475313 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1475313 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1475313 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-17T02:19:11.788Z
   [junit4]   2> 1475314 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1475314 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1475314 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1475314 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-17T02:19:11.789Z
   [junit4]   2> 1475329 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1475330 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1475340 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1475340 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1475389 INFO  (jetty-launcher-2682-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59183/solr
   [junit4]   2> 1475389 INFO  (jetty-launcher-2682-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59183/solr
   [junit4]   2> 1475393 INFO  (jetty-launcher-2682-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59183/solr
   [junit4]   2> 1475409 INFO  (jetty-launcher-2682-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59183/solr
   [junit4]   2> 1475457 INFO  (jetty-launcher-2682-thread-2) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1475458 INFO  (jetty-launcher-2682-thread-3) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1475458 INFO  (jetty-launcher-2682-thread-2) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:59188_solr
   [junit4]   2> 1475459 INFO  (jetty-launcher-2682-thread-1) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1475461 INFO  (jetty-launcher-2682-thread-2) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.Overseer Overseer 
(id=97471558558679048-127.0.0.1:59188_solr-n_0000000000) starting
   [junit4]   2> 1475463 INFO  (jetty-launcher-2682-thread-3) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:59189_solr
   [junit4]   2> 1475468 INFO  (jetty-launcher-2682-thread-1) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:59191_solr
   [junit4]   2> 1475469 INFO  (jetty-launcher-2682-thread-4) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1475471 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1475473 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1475473 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1475474 INFO  
(zkCallback-2699-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1475486 INFO  (jetty-launcher-2682-thread-4) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:59190_solr
   [junit4]   2> 1475487 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1475487 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1475487 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1475488 INFO  
(zkCallback-2699-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1475507 INFO  
(zkCallback-2699-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1475507 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1475507 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1475507 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1475518 INFO  (jetty-launcher-2682-thread-2) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:59188_solr
   [junit4]   2> 1475522 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1475523 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1475523 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1475523 INFO  
(zkCallback-2699-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1475647 INFO  (jetty-launcher-2682-thread-1) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node1/.
   [junit4]   2> 1475647 INFO  (jetty-launcher-2682-thread-4) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node4/.
   [junit4]   2> 1475651 INFO  (jetty-launcher-2682-thread-3) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node3/.
   [junit4]   2> 1475654 INFO  (jetty-launcher-2682-thread-2) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node2/.
   [junit4]   2> 1478047 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1478050 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59183/solr ready
   [junit4]   2> 1478093 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[F53CABC0B3C6DE6F]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForHashRouter
   [junit4]   2> 1478095 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[F53CABC0B3C6DE6F]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1478105 INFO  (qtp2009691747-12854) [n:127.0.0.1:59191_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> 1478110 INFO  
(OverseerThreadFactory-5824-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
routeFieldColl
   [junit4]   2> 1478236 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1478237 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1478257 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1478262 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1478264 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1478264 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1478265 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1478265 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1478398 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1478398 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1478398 INFO  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1478398 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1479294 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479294 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479306 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1479307 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1479310 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479311 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479314 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479347 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479358 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479360 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1479360 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1479364 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1479367 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1479370 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1479411 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1479434 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1479438 WARN  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1479439 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1479469 WARN  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479469 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479471 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479472 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node4/routeFieldColl_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node4/./routeFieldColl_shard1_replica1/data/]
   [junit4]   2> 1479472 WARN  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1479474 WARN  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1479475 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1479475 WARN  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1479475 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1479476 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node1/routeFieldColl_shard4_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node1/./routeFieldColl_shard4_replica2/data/]
   [junit4]   2> 1479476 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1479477 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1479477 WARN  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1479478 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node3/routeFieldColl_shard2_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node3/./routeFieldColl_shard2_replica1/data/]
   [junit4]   2> 1479478 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1479479 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1479479 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node1/routeFieldColl_shard2_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node1/./routeFieldColl_shard2_replica2/data/]
   [junit4]   2> 1479480 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1479480 WARN  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1479480 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node2/routeFieldColl_shard1_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node2/./routeFieldColl_shard1_replica2/data/]
   [junit4]   2> 1479481 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1479481 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1479482 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1479482 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1479482 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node2/routeFieldColl_shard3_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node2/./routeFieldColl_shard3_replica2/data/]
   [junit4]   2> 1479482 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node3/routeFieldColl_shard4_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node3/./routeFieldColl_shard4_replica1/data/]
   [junit4]   2> 1479487 WARN  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1479488 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1479489 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1479489 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node4/routeFieldColl_shard3_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001/tempDir-001/node4/./routeFieldColl_shard3_replica1/data/]
   [junit4]   2> 1479647 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479647 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1479651 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479651 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479652 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479652 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1479661 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479661 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479662 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479662 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1479663 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479663 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479665 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479665 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479666 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479666 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479669 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479669 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1479673 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479673 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479674 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479674 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1479677 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479677 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1479680 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479680 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479685 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3de6a4b5[routeFieldColl_shard2_replica2] main]
   [junit4]   2> 1479685 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1479685 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1479687 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479687 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479687 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479688 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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> 1479688 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479688 INFO  
(searcherExecutor-5836-thread-1-processing-n:127.0.0.1:59191_solr 
x:routeFieldColl_shard2_replica2 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:59191_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica2] Registered new searcher 
Searcher@3de6a4b5[routeFieldColl_shard2_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479689 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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 
1559544941682622464
   [junit4]   2> 1479692 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1479692 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1479693 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@290b16f3[routeFieldColl_shard3_replica2] main]
   [junit4]   2> 1479694 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479695 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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> 1479695 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479695 INFO  
(searcherExecutor-5838-thread-1-processing-n:127.0.0.1:59188_solr 
x:routeFieldColl_shard3_replica2 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:59188_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica2] Registered new searcher 
Searcher@290b16f3[routeFieldColl_shard3_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479695 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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 
1559544941688913920
   [junit4]   2> 1479698 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3a0caa9e[routeFieldColl_shard1_replica1] main]
   [junit4]   2> 1479699 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479700 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479700 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479700 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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 
1559544941694156800
   [junit4]   2> 1479700 INFO  
(searcherExecutor-5833-thread-1-processing-n:127.0.0.1:59190_solr 
x:routeFieldColl_shard1_replica1 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:59190_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica1] Registered new searcher 
Searcher@3a0caa9e[routeFieldColl_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479701 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2e05fb3f[routeFieldColl_shard4_replica2] main]
   [junit4]   2> 1479701 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@af35903[routeFieldColl_shard3_replica1] main]
   [junit4]   2> 1479705 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard3: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1479705 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479707 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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> 1479707 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479708 INFO  
(searcherExecutor-5840-thread-1-processing-n:127.0.0.1:59190_solr 
x:routeFieldColl_shard3_replica1 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:59190_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica1] Registered new searcher 
Searcher@af35903[routeFieldColl_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479708 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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 
1559544941702545408
   [junit4]   2> 1479709 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479711 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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> 1479711 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479711 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@20c397a0[routeFieldColl_shard1_replica2] main]
   [junit4]   2> 1479712 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1479713 INFO  
(searcherExecutor-5834-thread-1-processing-n:127.0.0.1:59191_solr 
x:routeFieldColl_shard4_replica2 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:59191_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica2] Registered new searcher 
Searcher@2e05fb3f[routeFieldColl_shard4_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479713 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479713 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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 
1559544941707788288
   [junit4]   2> 1479714 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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> 1479714 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479715 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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 
1559544941709885440
   [junit4]   2> 1479715 INFO  
(searcherExecutor-5837-thread-1-processing-n:127.0.0.1:59188_solr 
x:routeFieldColl_shard1_replica2 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:59188_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica2] Registered new searcher 
Searcher@20c397a0[routeFieldColl_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479717 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1479717 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e36d00a[routeFieldColl_shard4_replica1] main]
   [junit4]   2> 1479719 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479720 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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> 1479720 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479720 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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 
1559544941715128320
   [junit4]   2> 1479720 INFO  
(searcherExecutor-5839-thread-1-processing-n:127.0.0.1:59189_solr 
x:routeFieldColl_shard4_replica1 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:59189_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica1] Registered new searcher 
Searcher@7e36d00a[routeFieldColl_shard4_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479725 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1479726 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1479726 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:59191/solr/routeFieldColl_shard4_replica2/
   [junit4]   2> 1479726 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica2 url=http://127.0.0.1:59191/solr 
START replicas=[http://127.0.0.1:59189/solr/routeFieldColl_shard4_replica1/] 
nUpdates=100
   [junit4]   2> 1479726 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@498c4ec3[routeFieldColl_shard2_replica1] main]
   [junit4]   2> 1479727 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica2 url=http://127.0.0.1:59191/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1479729 INFO  (qtp968816651-12866) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4 r:core_node4 x:routeFieldColl_shard4_replica1] 
o.a.s.c.S.Request [routeFieldColl_shard4_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1479730 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1479730 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
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> 1479731 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1479732 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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> 1479733 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1479733 INFO  
(searcherExecutor-5835-thread-1-processing-n:127.0.0.1:59189_solr 
x:routeFieldColl_shard2_replica1 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:59189_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica1] Registered new searcher 
Searcher@498c4ec3[routeFieldColl_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1479733 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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 
1559544941728759808
   [junit4]   2> 1479738 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:59191/solr/routeFieldColl_shard4_replica2/ shard4
   [junit4]   2> 1479850 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1479850 INFO  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1479850 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1479850 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1479907 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1479912 INFO  (qtp2009691747-12856) [n:127.0.0.1:59191_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=1655
   [junit4]   2> 1480025 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1480025 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1480025 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1480025 INFO  
(zkCallback-2699-thread-1-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1480216 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1480216 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1480216 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:59188/solr/routeFieldColl_shard3_replica2/
   [junit4]   2> 1480216 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica2 url=http://127.0.0.1:59188/solr 
START replicas=[http://127.0.0.1:59190/solr/routeFieldColl_shard3_replica1/] 
nUpdates=100
   [junit4]   2> 1480216 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica2 url=http://127.0.0.1:59188/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1480216 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1480216 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1480216 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:59190/solr/routeFieldColl_shard1_replica1/
   [junit4]   2> 1480217 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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:59190/solr 
START replicas=[http://127.0.0.1:59188/solr/routeFieldColl_shard1_replica2/] 
nUpdates=100
   [junit4]   2> 1480217 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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:59190/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1480218 INFO  (qtp1391405782-12872) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 x:routeFieldColl_shard3_replica1] 
o.a.s.c.S.Request [routeFieldColl_shard3_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1480218 INFO  (qtp2019694437-12849) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1 r:core_node8 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> 1480218 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1480218 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
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> 1480219 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1480219 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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> 1480220 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1480220 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1480220 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:59191/solr/routeFieldColl_shard2_replica2/
   [junit4]   2> 1480221 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica2 url=http://127.0.0.1:59191/solr 
START replicas=[http://127.0.0.1:59189/solr/routeFieldColl_shard2_replica1/] 
nUpdates=100
   [junit4]   2> 1480222 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica2 url=http://127.0.0.1:59191/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1480222 INFO  (qtp968816651-12874) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2 r:core_node5 x:routeFieldColl_shard2_replica1] 
o.a.s.c.S.Request [routeFieldColl_shard2_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1480223 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1480223 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
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> 1480226 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:59190/solr/routeFieldColl_shard1_replica1/ shard1
   [junit4]   2> 1480226 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:59188/solr/routeFieldColl_shard3_replica2/ shard3
   [junit4]   2> 1480231 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:59191/solr/routeFieldColl_shard2_replica2/ shard2
   [junit4]   2> 1480344 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1480344 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1480344 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1480344 INFO  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1480381 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1480388 INFO  (qtp2019694437-12846) [n:127.0.0.1:59188_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=2123
   [junit4]   2> 1480388 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1480390 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1480394 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_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=2158
   [junit4]   2> 1480394 INFO  (qtp2009691747-12855) [n:127.0.0.1:59191_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=2157
   [junit4]   2> 1480503 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1480503 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1480503 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1480503 INFO  
(zkCallback-2699-thread-2-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1480724 INFO  (qtp1391405782-12869) [n:127.0.0.1:59190_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=2460
   [junit4]   2> 1480726 INFO  (qtp2019694437-12848) [n:127.0.0.1:59188_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=2461
   [junit4]   2> 1480809 INFO  (qtp968816651-12862) [n:127.0.0.1:59189_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=2546
   [junit4]   2> 1480836 INFO  (qtp968816651-12864) [n:127.0.0.1:59189_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=2572
   [junit4]   2> 1480842 INFO  (qtp2009691747-12854) [n:127.0.0.1:59191_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> 1480953 INFO  
(zkCallback-2700-thread-1-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_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> 1480953 INFO  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_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> 1480953 INFO  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_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> 1480953 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_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> 1481846 INFO  (qtp2009691747-12854) [n:127.0.0.1:59191_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=3740
   [junit4]   2> 1481863 INFO  (qtp1391405782-12876) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 x:routeFieldColl_shard3_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:59188/solr/routeFieldColl_shard3_replica2/&wt=javabin&version=2}{add=[6
 (1559544943949643776), 7 (1559544943958032384)]} 0 4
   [junit4]   2> 1481864 INFO  (qtp2019694437-12850) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica2]  
webapp=/solr path=/update 
params={_stateVer_=routeFieldColl:6&wt=javabin&version=2}{add=[6 
(1559544943949643776), 7 (1559544943958032384)]} 0 12
   [junit4]   2> 1481866 INFO  (qtp2019694437-12851) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1 r:core_node8 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:59190/solr/routeFieldColl_shard1_replica1/&wt=javabin&version=2}{add=[8
 (1559544943949643776)]} 0 6
   [junit4]   2> 1481866 INFO  (qtp1391405782-12873) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1 r:core_node2 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 
(1559544943949643776)]} 0 15
   [junit4]   2> 1481868 INFO  (qtp2019694437-12844) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1 r:core_node8 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> 1481869 INFO  (qtp1391405782-12871) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1 r:core_node2 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> 1481869 INFO  (qtp1391405782-12871) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard1 r:core_node2 x:routeFieldColl_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d3122cd
   [junit4]   2> 1481870 INFO  (qtp968816651-12843) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2 r:core_node5 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> 1481870 INFO  (qtp968816651-12843) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2 r:core_node5 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1481871 INFO  (qtp2019694437-12847) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3 r:core_node7 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> 1481871 INFO  (qtp2019694437-12847) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@34ac4550
   [junit4]   2> 1481874 INFO  (qtp2019694437-12844) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard1 r:core_node8 x:routeFieldColl_shard1_replica2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5fcb00d5
   [junit4]   2> 1481875 INFO  (qtp968816651-12843) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2 r:core_node5 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1481875 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 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> 1481875 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 x:routeFieldColl_shard3_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@57ce2096
   [junit4]   2> 1481875 INFO  (qtp968816651-12843) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard2 r:core_node5 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:59190/solr/routeFieldColl_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 1481876 INFO  (qtp2009691747-12858) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4 r:core_node3 x:routeFieldColl_shard4_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1481876 INFO  (qtp2009691747-12858) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4 r:core_node3 x:routeFieldColl_shard4_replica2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1481876 INFO  (qtp2009691747-12860) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2 r:core_node1 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> 1481876 INFO  (qtp2009691747-12860) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2 r:core_node1 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1481879 INFO  (qtp2009691747-12858) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4 r:core_node3 x:routeFieldColl_shard4_replica2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1481879 INFO  (qtp968816651-12863) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4 r:core_node4 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1481879 INFO  (qtp2009691747-12860) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2 r:core_node1 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1481879 INFO  (qtp968816651-12863) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4 r:core_node4 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1481880 INFO  (qtp2009691747-12858) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard4 r:core_node3 x:routeFieldColl_shard4_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard4_replica2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59190/solr/routeFieldColl_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 1481901 INFO  (qtp968816651-12863) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4 r:core_node4 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1481901 INFO  (qtp2009691747-12860) [n:127.0.0.1:59191_solr 
c:routeFieldColl s:shard2 r:core_node1 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:59190/solr/routeFieldColl_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 25
   [junit4]   2> 1481901 INFO  (qtp968816651-12863) [n:127.0.0.1:59189_solr 
c:routeFieldColl s:shard4 r:core_node4 x:routeFieldColl_shard4_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard4_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:59190/solr/routeFieldColl_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 24
   [junit4]   2> 1482135 INFO  
(OverseerCollectionConfigSetProcessor-97471558558679048-127.0.0.1:59188_solr-n_0000000000)
 [n:127.0.0.1:59188_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1482147 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 x:routeFieldColl_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@31f3b4dd[routeFieldColl_shard3_replica1] main]
   [junit4]   2> 1482147 INFO  (qtp2019694437-12847) [n:127.0.0.1:59188_solr 
c:routeFieldColl s:shard3 r:core_node7 x:routeFieldColl_shard3_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@68db49fc[routeFieldColl_shard3_replica2] main]
   [junit4]   2> 1482147 INFO  (qtp1391405782-12857) [n:127.0.0.1:59190_solr 
c:routeFieldColl s:shard3 r:core_node6 x:routeFieldColl_shard3_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1482147 INFO  
(searcherExecutor-5840-thread-1-processing-n:127.0.0.1:59190_solr 
x:routeFieldColl_shard3_replica1 s:shard3 c:routeFieldColl r:core_node6) 
[n:127.0.0.1:59190_solr c:routeFieldColl s:shard3 r:core_node6 
x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica1] Registered new searcher Searcher@31f

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

nit4]   2> 1539674 INFO  (jetty-launcher-2682-thread-5) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=336824337
   [junit4]   2> 1539675 INFO  (jetty-launcher-2682-thread-6) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1207373345
   [junit4]   2> 1539676 INFO  (jetty-launcher-2682-thread-7) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@20ffb419{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1539685 INFO  (jetty-launcher-2682-thread-8) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@740539e0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1539695 INFO  (jetty-launcher-2682-thread-7) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1514061301
   [junit4]   2> 1539695 INFO  (jetty-launcher-2682-thread-8) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2089190512
   [junit4]   2> 1539707 INFO  (jetty-launcher-2682-thread-6) [    ] 
o.a.s.c.Overseer Overseer 
(id=97471558558679048-127.0.0.1:59188_solr-n_0000000000) closing
   [junit4]   2> 1539708 INFO  
(OverseerStateUpdate-97471558558679048-127.0.0.1:59188_solr-n_0000000000) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:59188_solr
   [junit4]   2> 1539718 WARN  
(zkCallback-2706-thread-2-processing-n:127.0.0.1:59190_solr) 
[n:127.0.0.1:59190_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1539719 INFO  (jetty-launcher-2682-thread-5) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1539719 INFO  (jetty-launcher-2682-thread-5) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@40547320{/solr,null,UNAVAILABLE}
   [junit4]   2> 1539720 INFO  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (1)
   [junit4]   2> 1539730 WARN  
(zkCallback-2699-thread-3-processing-n:127.0.0.1:59188_solr) 
[n:127.0.0.1:59188_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1539731 INFO  (jetty-launcher-2682-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1539731 INFO  (jetty-launcher-2682-thread-6) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5cc8365a{/solr,null,UNAVAILABLE}
   [junit4]   2> 1541222 WARN  
(zkCallback-2701-thread-1-processing-n:127.0.0.1:59191_solr) 
[n:127.0.0.1:59191_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1541222 WARN  
(zkCallback-2700-thread-2-processing-n:127.0.0.1:59189_solr) 
[n:127.0.0.1:59189_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1541222 INFO  (jetty-launcher-2682-thread-8) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1541222 INFO  (jetty-launcher-2682-thread-7) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1541222 INFO  (jetty-launcher-2682-thread-8) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1a7b56b4{/solr,null,UNAVAILABLE}
   [junit4]   2> 1541222 INFO  (jetty-launcher-2682-thread-7) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@665ca0f9{/solr,null,UNAVAILABLE}
   [junit4]   2> 1541226 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59183 59183
   [junit4]   2> 1542341 INFO  (Thread-2361) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:59183 59183
   [junit4]   2> 1544635 WARN  (Thread-2361) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        14      /solr/configs/conf
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        5       /solr/collections/implicitcoll/state.json
   [junit4]   2>        4       
/solr/collections/testCreateShardRepFactor/state.json
   [junit4]   2>        4       /solr/collections/routeFieldColl/state.json
   [junit4]   2>        4       /solr/collections/withShardField/state.json
   [junit4]   2>        2       
/solr/collections/implicitcoll/leader_elect/b/election/97471558558679052-core_node10-n_0000000001
   [junit4]   2>        2       
/solr/collections/implicitcoll/leader_elect/c/election/97471558558679050-core_node1-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        80      /solr/overseer/queue
   [junit4]   2>        47      /solr/overseer/collection-queue-work
   [junit4]   2>        32      /solr/collections
   [junit4]   2>        9       /solr/live_nodes
   [junit4]   2>        6       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 1544635 INFO  
(SUITE-CustomCollectionTest-seed#[F53CABC0B3C6DE6F]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_F53CABC0B3C6DE6F-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{shard_s=Lucene50(blocksize=128), _version_=PostingsFormat(name=MockRandom), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
_route_=PostingsFormat(name=Memory)}, docValues:{}, maxPointsInLeafNode=1192, 
maxMBSortInHeap=5.045216719070978, sim=RandomSimilarity(queryNorm=false): {}, 
locale=en-PH, timezone=PLT
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=116678896,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrIndexConfig, 
SliceStateTest, IndexSchemaTest, SolrCmdDistributorTest, HdfsNNFailoverTest, 
SegmentsInfoRequestHandlerTest, BitVectorTest, TestCoreDiscovery, 
CoreAdminCreateDiscoverTest, TestMacros, CloudExitableDirectoryReaderTest, 
URLClassifyProcessorTest, ResourceLoaderTest, SpellPossibilityIteratorTest, 
TestHdfsCloudBackupRestore, FullSolrCloudDistribCmdsTest, BlockCacheTest, 
TestRawResponseWriter, DistanceFunctionTest, BlobRepositoryCloudTest, 
SolrCloudExampleTest, DistributedFacetPivotLongTailTest, NoCacheHeaderTest, 
SpatialRPTFieldTypeTest, AnalysisErrorHandlingTest, 
TestCopyFieldCollectionResource, TestCodecSupport, LoggingHandlerTest, 
TestPathTrie, DirectoryFactoryTest, TestSolrQueryParser, 
TestFuzzyAnalyzedSuggestions, ParsingFieldUpdateProcessorsTest, 
TestClusterProperties, SpellCheckComponentTest, 
TestTolerantUpdateProcessorCloud, TestFieldCache, TestRandomFlRTGCloud, 
TestRTimerTree, TestPHPSerializedResponseWriter, 
FieldAnalysisRequestHandlerTest, LeaderElectionIntegrationTest, 
ConvertedLegacyTest, TestRecoveryHdfs, CachingDirectoryFactoryTest, 
HdfsRecoveryZkTest, TestBackupRepositoryFactory, TestTrie, 
CollectionsAPIAsyncDistributedZkTest, QueryParsingTest, 
HdfsBasicDistributedZkTest, DistributedDebugComponentTest, 
TestDeleteCollectionOnDownNodes, TestIntervalFaceting, TestReqParamsAPI, 
RemoteQueryErrorTest, TestImplicitCoreProperties, 
TestDynamicFieldCollectionResource, TestSha256AuthenticationProvider, 
TestNumericTerms32, HdfsSyncSliceTest, TestRealTimeGet, 
TestMiniSolrCloudCluster, TestCustomStream, TestSystemCollAutoCreate, 
TestSolrCloudWithDelegationTokens, ClassificationUpdateProcessorFactoryTest, 
HdfsChaosMonkeySafeLeaderTest, RAMDirectoryFactoryTest, RuleEngineTest, 
CdcrReplicationDistributedZkTest, TestSolrQueryParserResource, 
TestJavabinTupleStreamParser, TestManagedStopFilterFactory, HttpPartitionTest, 
TestCloudSchemaless, TestUnifiedSolrHighlighter, BaseCdcrDistributedZkTest, 
TestSolrConfigHandlerCloud, RegexBoostProcessorTest, TestUseDocValuesAsStored, 
TestQueryUtils, SolrMetricReporterTest, InfixSuggestersTest, TestUtils, 
TestDownShardTolerantSearch, BasicDistributedZk2Test, TestIndexSearcher, 
SmileWriterTest, LukeRequestHandlerTest, DistributedIntervalFacetingTest, 
CdcrRequestHandlerTest, PreAnalyzedFieldTest, TestAuthenticationFramework, 
TestFieldCacheSanityChecker, TestConfigSetsAPIZkFailure, ClusterStateTest, 
OverseerStatusTest, TestLuceneMatchVersion, DirectUpdateHandlerOptimizeTest, 
TestDistribIDF, HttpSolrCallGetCoreTest, TestNoOpRegenerator, 
TestNonDefinedSimilarityFactory, ClassificationUpdateProcessorTest, 
TestZkChroot, TestManagedSchemaThreadSafety, TestManagedSchema, 
TestFieldCacheSortRandom, TestLMDirichletSimilarityFactory, 
TestConfigSetImmutable, TestJettySolrRunner, TestSolr4Spatial, 
TestImpersonationWithHadoopAuth, ResponseLogComponentTest, 
UpdateRequestProcessorFactoryTest, SolrCoreMetricManagerTest, 
TestSubQueryTransformer, OpenCloseCoreStressTest, TestConfigSets, 
TestInitParams, AddSchemaFieldsUpdateProcessorFactoryTest, 
HdfsDirectoryFactoryTest, TestPKIAuthenticationPlugin, 
TestSerializedLuceneMatchVersion, ShowFileRequestHandlerTest, 
ConnectionManagerTest, UpdateParamsTest, TestBulkSchemaAPI, 
TestLMJelinekMercerSimilarityFactory, TestConfigSetsAPI, UUIDFieldTest, 
TestFieldCollectionResource, TestValueSourceCache, TestSolrCoreProperties, 
ReturnFieldsTest, ActionThrottleTest, TestSubQueryTransformerDistrib, 
ReplicationFactorTest, VMParamsZkACLAndCredentialsProvidersTest, 
FileBasedSpellCheckerTest, TestSimpleTrackingShardHandler, 
NotRequiredUniqueKeyTest, OverseerModifyCollectionTest, TestConfig, 
TestUninvertingReader, ExternalFileFieldSortTest, TestStressRecovery, 
TestExactStatsCache, RollingRestartTest, SuggesterTSTTest, CustomCollectionTest]
   [junit4] Completed [302/694 (1!)] on J1 in 69.50s, 4 tests, 1 error <<< 
FAILURES!

[...truncated 63876 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to