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

2 tests failed.
FAILED:  org.apache.solr.cloud.TestPullReplica.testKillLeader

Error Message:
Replica core_node4 not up to date after 10 seconds expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: Replica core_node4 not up to date after 10 seconds 
expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([232C19C1B0204702:6A3AED75D29BD354]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.solr.cloud.TestPullReplica.waitForNumDocsInAllReplicas(TestPullReplica.java:510)
        at 
org.apache.solr.cloud.TestPullReplica.doTestNoLeader(TestPullReplica.java:458)
        at 
org.apache.solr.cloud.TestPullReplica.testKillLeader(TestPullReplica.java:290)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


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([232C19C1B0204702:767CF1531CD988F2]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:842)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:808)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
        at 
org.apache.solr.cloud.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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13194 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> 2414033 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/init-core-data-001
   [junit4]   2> 2414034 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2414040 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-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> 2414041 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001
   [junit4]   2> 2414041 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2414041 INFO  (Thread-4312) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2414042 INFO  (Thread-4312) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2414045 ERROR (Thread-4312) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2414145 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:56041
   [junit4]   2> 2414161 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2414162 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2414161 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2414162 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2414164 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@75608d16{/solr,null,AVAILABLE}
   [junit4]   2> 2414165 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@112957f{HTTP/1.1,[http/1.1]}{127.0.0.1:56044}
   [junit4]   2> 2414166 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.e.j.s.Server Started @2418612ms
   [junit4]   2> 2414166 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56044}
   [junit4]   2> 2414166 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2365ae1c{/solr,null,AVAILABLE}
   [junit4]   2> 2414166 ERROR (jetty-launcher-6070-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3a6caa2f{/solr,null,AVAILABLE}
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@12316a11{/solr,null,AVAILABLE}
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@cd91aed{HTTP/1.1,[http/1.1]}{127.0.0.1:56046}
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.e.j.s.Server Started @2418613ms
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4edd8dd2{HTTP/1.1,[http/1.1]}{127.0.0.1:56047}
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56046}
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.e.j.s.Server Started @2418614ms
   [junit4]   2> 2414168 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56047}
   [junit4]   2> 2414168 ERROR (jetty-launcher-6070-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2414168 ERROR (jetty-launcher-6070-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2414168 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@47872492{HTTP/1.1,[http/1.1]}{127.0.0.1:56045}
   [junit4]   2> 2414169 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.e.j.s.Server Started @2418616ms
   [junit4]   2> 2414169 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56045}
   [junit4]   2> 2414170 ERROR (jetty-launcher-6070-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2414169 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 2414170 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2414170 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2414170 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-30T03:47:52.302Z
   [junit4]   2> 2414171 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.2.0
   [junit4]   2> 2414171 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2414172 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2414172 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-30T03:47:52.304Z
   [junit4]   2> 2414167 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-30T03:47:52.299Z
   [junit4]   2> 2414173 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2414173 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2414173 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-30T03:47:52.305Z
   [junit4]   2> 2414176 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2414176 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2414176 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2414177 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2414187 INFO  (jetty-launcher-6070-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56041/solr
   [junit4]   2> 2414189 INFO  (jetty-launcher-6070-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56041/solr
   [junit4]   2> 2414190 INFO  (jetty-launcher-6070-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56041/solr
   [junit4]   2> 2414191 INFO  (jetty-launcher-6070-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56041/solr
   [junit4]   2> 2414663 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2414664 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2414664 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2414665 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:56044_solr
   [junit4]   2> 2414665 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2414667 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56046_solr
   [junit4]   2> 2414667 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56045_solr
   [junit4]   2> 2414668 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56047_solr
   [junit4]   2> 2414668 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.Overseer Overseer 
(id=98915796397326347-127.0.0.1:56044_solr-n_0000000000) starting
   [junit4]   2> 2414682 INFO  
(zkCallback-6091-thread-1-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2414690 INFO  
(zkCallback-6092-thread-1-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2414690 INFO  
(zkCallback-6090-thread-1-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2414690 INFO  
(zkCallback-6089-thread-1-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2414690 INFO  
(zkCallback-6091-thread-1-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2414699 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56044_solr
   [junit4]   2> 2414722 INFO  
(zkCallback-6092-thread-1-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2414722 INFO  
(zkCallback-6090-thread-1-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2414722 INFO  
(zkCallback-6089-thread-1-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2414722 INFO  
(zkCallback-6091-thread-1-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2414898 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56046.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414918 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56047.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414926 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56046.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414926 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56046.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414927 INFO  (jetty-launcher-6070-thread-2) 
[n:127.0.0.1:56046_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node2/.
   [junit4]   2> 2414931 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56047.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414931 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56047.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414932 INFO  (jetty-launcher-6070-thread-3) 
[n:127.0.0.1:56047_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node3/.
   [junit4]   2> 2414958 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56045.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2414988 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56044.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2415052 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56045.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2415053 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56045.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2415057 INFO  (jetty-launcher-6070-thread-4) 
[n:127.0.0.1:56045_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node4/.
   [junit4]   2> 2415059 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56044.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2415059 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56044.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2415060 INFO  (jetty-launcher-6070-thread-1) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node1/.
   [junit4]   2> 2415173 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2415176 INFO  
(SUITE-CustomCollectionTest-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56041/solr ready
   [junit4]   2> 2415212 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[232C19C1B0204702]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForHashRouter
   [junit4]   2> 2415214 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[232C19C1B0204702]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2415218 INFO  (qtp1840274850-25570) [n:127.0.0.1:56044_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&nrtReplicas=2&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2415220 INFO  
(OverseerThreadFactory-10683-thread-1-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
routeFieldColl
   [junit4]   2> 2415333 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"routeFieldColl_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56047/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415336 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"routeFieldColl_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56045/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415340 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"routeFieldColl_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56046/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415343 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"routeFieldColl_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56044/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415346 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"routeFieldColl_shard3_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56047/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415350 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"routeFieldColl_shard3_replica_n10",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56045/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415354 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard4",
   [junit4]   2>   "core":"routeFieldColl_shard4_replica_n12",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56046/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415356 INFO  
(OverseerStateUpdate-98915796397326347-127.0.0.1:56044_solr-n_0000000000) 
[n:127.0.0.1:56044_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"routeFieldColl",
   [junit4]   2>   "shard":"shard4",
   [junit4]   2>   "core":"routeFieldColl_shard4_replica_n14",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56044/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2415576 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica_n1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415580 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2415582 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica_n10&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415583 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2415584 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica_n8&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415584 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica_n6&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415584 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica_n2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415585 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2415586 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica_n14&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415588 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica_n4&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415588 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2415589 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica_n12&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2415707 INFO  
(zkCallback-6091-thread-1-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_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> 2415707 INFO  
(zkCallback-6090-thread-1-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_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> 2415707 INFO  
(zkCallback-6092-thread-1-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2415707 INFO  
(zkCallback-6089-thread-1-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_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> 2415707 INFO  
(zkCallback-6091-thread-2-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_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> 2415708 INFO  
(zkCallback-6089-thread-2-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_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> 2415708 INFO  
(zkCallback-6090-thread-2-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_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> 2415709 INFO  
(zkCallback-6092-thread-1-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2415709 INFO  
(zkCallback-6092-thread-2-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2416642 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416643 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416643 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416677 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416679 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416680 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416691 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416692 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica_n8] Schema name=cloud-dynamic
   [junit4]   2> 2416693 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica_n4] Schema name=cloud-dynamic
   [junit4]   2> 2416700 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 2416703 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 2416704 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica_n12] Schema 
name=cloud-dynamic
   [junit4]   2> 2416711 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica_n10] Schema 
name=cloud-dynamic
   [junit4]   2> 2416713 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 2416768 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica_n14] Schema 
name=cloud-dynamic
   [junit4]   2> 2416805 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica_n6] Schema name=cloud-dynamic
   [junit4]   2> 2416906 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416907 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica_n4' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416907 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56046.solr.core.routeFieldColl.shard2.replica_n4' (registry 
'solr.core.routeFieldColl.shard2.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416908 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416908 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.SolrCore [[routeFieldColl_shard2_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node2/routeFieldColl_shard2_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node2/./routeFieldColl_shard2_replica_n4/data/]
   [junit4]   2> 2416910 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416910 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica_n12' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416916 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416916 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica_n14' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416917 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56044.solr.core.routeFieldColl.shard4.replica_n14' (registry 
'solr.core.routeFieldColl.shard4.replica_n14') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416917 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416917 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.c.SolrCore [[routeFieldColl_shard4_replica_n14] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node1/routeFieldColl_shard4_replica_n14],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node1/./routeFieldColl_shard4_replica_n14/data/]
   [junit4]   2> 2416919 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56046.solr.core.routeFieldColl.shard4.replica_n12' (registry 
'solr.core.routeFieldColl.shard4.replica_n12') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416919 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416919 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.SolrCore [[routeFieldColl_shard4_replica_n12] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node2/routeFieldColl_shard4_replica_n12],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node2/./routeFieldColl_shard4_replica_n12/data/]
   [junit4]   2> 2416923 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416923 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica_n1' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416924 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56047.solr.core.routeFieldColl.shard1.replica_n1' (registry 
'solr.core.routeFieldColl.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416925 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416925 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.SolrCore [[routeFieldColl_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node3/routeFieldColl_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node3/./routeFieldColl_shard1_replica_n1/data/]
   [junit4]   2> 2416926 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416926 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica_n10' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416926 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56045.solr.core.routeFieldColl.shard3.replica_n10' (registry 
'solr.core.routeFieldColl.shard3.replica_n10') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416926 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416926 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.SolrCore [[routeFieldColl_shard3_replica_n10] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node4/routeFieldColl_shard3_replica_n10],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node4/./routeFieldColl_shard3_replica_n10/data/]
   [junit4]   2> 2416982 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416982 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica_n2' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416983 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56045.solr.core.routeFieldColl.shard1.replica_n2' (registry 
'solr.core.routeFieldColl.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416983 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416983 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.c.SolrCore [[routeFieldColl_shard1_replica_n2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node4/routeFieldColl_shard1_replica_n2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node4/./routeFieldColl_shard1_replica_n2/data/]
   [junit4]   2> 2416987 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416988 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica_n8' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416988 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56047.solr.core.routeFieldColl.shard3.replica_n8' (registry 
'solr.core.routeFieldColl.shard3.replica_n8') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416988 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416988 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.c.SolrCore [[routeFieldColl_shard3_replica_n8] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node3/routeFieldColl_shard3_replica_n8],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node3/./routeFieldColl_shard3_replica_n8/data/]
   [junit4]   2> 2416990 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2416990 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica_n6' 
using configuration from collection routeFieldColl, trusted=true
   [junit4]   2> 2416991 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56044.solr.core.routeFieldColl.shard2.replica_n6' (registry 
'solr.core.routeFieldColl.shard2.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7098896b
   [junit4]   2> 2416991 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2416991 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.c.SolrCore [[routeFieldColl_shard2_replica_n6] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node1/routeFieldColl_shard2_replica_n6],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_232C19C1B0204702-001/tempDir-001/node1/./routeFieldColl_shard2_replica_n6/data/]
   [junit4]   2> 2417241 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417241 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417245 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417245 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417247 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417247 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417256 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417256 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417279 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417279 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417286 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5a51b142[routeFieldColl_shard4_replica_n12] main]
   [junit4]   2> 2417286 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417287 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417288 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417288 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417289 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417290 INFO  
(searcherExecutor-10693-thread-1-processing-n:127.0.0.1:56046_solr 
x:routeFieldColl_shard4_replica_n12 s:shard4 c:routeFieldColl r:core_node15) 
[n:127.0.0.1:56046_solr c:routeFieldColl s:shard4 r:core_node15 
x:routeFieldColl_shard4_replica_n12] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica_n12] Registered new searcher 
Searcher@5a51b142[routeFieldColl_shard4_replica_n12] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417290 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745760899072
   [junit4]   2> 2417291 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417291 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417294 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417294 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417302 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417302 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417308 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417308 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417315 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard4: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2417316 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5fb5465a[routeFieldColl_shard2_replica_n4] main]
   [junit4]   2> 2417324 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417325 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417327 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417328 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417336 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4d877a40[routeFieldColl_shard3_replica_n8] main]
   [junit4]   2> 2417336 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417337 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417338 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417338 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417339 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417339 INFO  
(searcherExecutor-10692-thread-1-processing-n:127.0.0.1:56046_solr 
x:routeFieldColl_shard2_replica_n4 s:shard2 c:routeFieldColl r:core_node7) 
[n:127.0.0.1:56046_solr c:routeFieldColl s:shard2 r:core_node7 
x:routeFieldColl_shard2_replica_n4] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica_n4] Registered new searcher 
Searcher@5fb5465a[routeFieldColl_shard2_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417339 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745812279296
   [junit4]   2> 2417343 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417343 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417348 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@54dfb1c6[routeFieldColl_shard3_replica_n10] main]
   [junit4]   2> 2417364 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@24b129af[routeFieldColl_shard4_replica_n14] main]
   [junit4]   2> 2417372 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9998ms
   [junit4]   2> 2417372 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417373 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@aced9df[routeFieldColl_shard1_replica_n1] main]
   [junit4]   2> 2417374 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417375 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417376 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417377 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417377 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745852125184
   [junit4]   2> 2417378 INFO  
(searcherExecutor-10695-thread-1-processing-n:127.0.0.1:56047_solr 
x:routeFieldColl_shard1_replica_n1 s:shard1 c:routeFieldColl r:core_node3) 
[n:127.0.0.1:56047_solr c:routeFieldColl s:shard1 r:core_node3 
x:routeFieldColl_shard1_replica_n1] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica_n1] Registered new searcher 
Searcher@aced9df[routeFieldColl_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417378 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417386 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2417387 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417388 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745863659520
   [junit4]   2> 2417388 INFO  
(searcherExecutor-10696-thread-1-processing-n:127.0.0.1:56045_solr 
x:routeFieldColl_shard3_replica_n10 s:shard3 c:routeFieldColl r:core_node13) 
[n:127.0.0.1:56045_solr c:routeFieldColl s:shard3 r:core_node13 
x:routeFieldColl_shard3_replica_n10] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica_n10] Registered new searcher 
Searcher@54dfb1c6[routeFieldColl_shard3_replica_n10] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417389 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417390 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417391 INFO  
(searcherExecutor-10694-thread-1-processing-n:127.0.0.1:56044_solr 
x:routeFieldColl_shard4_replica_n14 s:shard4 c:routeFieldColl r:core_node16) 
[n:127.0.0.1:56044_solr c:routeFieldColl s:shard4 r:core_node16 
x:routeFieldColl_shard4_replica_n14] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica_n14] Registered new searcher 
Searcher@24b129af[routeFieldColl_shard4_replica_n14] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417391 INFO  (qtp1840274850-25576) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745866805248
   [junit4]   2> 2417424 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@78d3a1a1[routeFieldColl_shard1_replica_n2] main]
   [junit4]   2> 2417425 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417426 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417427 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417427 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417430 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard3: total=2 found=1 timeoutin=9998ms
   [junit4]   2> 2417431 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417431 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417432 INFO  
(searcherExecutor-10697-thread-1-processing-n:127.0.0.1:56045_solr 
x:routeFieldColl_shard1_replica_n2 s:shard1 c:routeFieldColl r:core_node5) 
[n:127.0.0.1:56045_solr c:routeFieldColl s:shard1 r:core_node5 
x:routeFieldColl_shard1_replica_n2] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica_n2] Registered new searcher 
Searcher@78d3a1a1[routeFieldColl_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417432 INFO  
(searcherExecutor-10698-thread-1-processing-n:127.0.0.1:56047_solr 
x:routeFieldColl_shard3_replica_n8 s:shard3 c:routeFieldColl r:core_node11) 
[n:127.0.0.1:56047_solr c:routeFieldColl s:shard3 r:core_node11 
x:routeFieldColl_shard3_replica_n8] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica_n8] Registered new searcher 
Searcher@4d877a40[routeFieldColl_shard3_replica_n8] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417432 INFO  (qtp1436849048-25568) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745909796864
   [junit4]   2> 2417433 INFO  (qtp510448278-25586) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745910845440
   [junit4]   2> 2417444 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2417444 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2417452 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2417452 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2417494 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@656127ad[routeFieldColl_shard2_replica_n6] main]
   [junit4]   2> 2417496 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2417497 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2417498 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2417499 INFO  
(searcherExecutor-10699-thread-1-processing-n:127.0.0.1:56044_solr 
x:routeFieldColl_shard2_replica_n6 s:shard2 c:routeFieldColl r:core_node9) 
[n:127.0.0.1:56044_solr c:routeFieldColl s:shard2 r:core_node9 
x:routeFieldColl_shard2_replica_n6] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica_n6] Registered new searcher 
Searcher@656127ad[routeFieldColl_shard2_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2417499 INFO  (qtp1840274850-25571) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1582652745980051456
   [junit4]   2> 2417541 INFO  
(zkCallback-6091-thread-3-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_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> 2417541 INFO  
(zkCallback-6090-thread-2-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_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> 2417541 INFO  
(zkCallback-6092-thread-2-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2417541 INFO  
(zkCallback-6090-thread-1-processing-n:127.0.0.1:56046_solr) 
[n:127.0.0.1:56046_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> 2417541 INFO  
(zkCallback-6092-thread-3-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2417541 INFO  
(zkCallback-6089-thread-2-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_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> 2417541 INFO  
(zkCallback-6089-thread-1-processing-n:127.0.0.1:56047_solr) 
[n:127.0.0.1:56047_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> 2417541 INFO  
(zkCallback-6091-thread-2-processing-n:127.0.0.1:56044_solr) 
[n:127.0.0.1:56044_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> 2417541 INFO  
(zkCallback-6092-thread-1-processing-n:127.0.0.1:56045_solr) 
[n:127.0.0.1:56045_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> 2417827 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2417827 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2417827 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56046/solr/routeFieldColl_shard4_replica_n12/
   [junit4]   2> 2417828 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard4_replica_n12 
url=http://127.0.0.1:56046/solr START 
replicas=[http://127.0.0.1:56044/solr/routeFieldColl_shard4_replica_n14/] 
nUpdates=100
   [junit4]   2> 2417844 INFO  (qtp1840274850-25577) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard4 r:core_node16 x:routeFieldColl_shard4_replica_n14] 
o.a.s.c.S.Request [routeFieldColl_shard4_replica_n14]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2417873 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2417873 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2417873 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56046/solr/routeFieldColl_shard2_replica_n4/
   [junit4]   2> 2417874 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard2_replica_n4 
url=http://127.0.0.1:56046/solr START 
replicas=[http://127.0.0.1:56044/solr/routeFieldColl_shard2_replica_n6/] 
nUpdates=100
   [junit4]   2> 2417875 INFO  (qtp1840274850-25577) [n:127.0.0.1:56044_solr 
c:routeFieldColl s:shard2 r:core_node9 x:routeFieldColl_shard2_replica_n6] 
o.a.s.c.S.Request [routeFieldColl_shard2_replica_n6]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2417896 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2417896 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2417896 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56047/solr/routeFieldColl_shard1_replica_n1/
   [junit4]   2> 2417896 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard1_replica_n1 
url=http://127.0.0.1:56047/solr START 
replicas=[http://127.0.0.1:56045/solr/routeFieldColl_shard1_replica_n2/] 
nUpdates=100
   [junit4]   2> 2417898 INFO  (qtp1436849048-25569) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica_n2] 
o.a.s.c.S.Request [routeFieldColl_shard1_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2417936 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2417936 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2417936 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56045/solr/routeFieldColl_shard3_replica_n10/
   [junit4]   2> 2417936 INFO  (qtp1436849048-25566) [n:127.0.0.1:56045_solr 
c:routeFieldColl s:shard3 r:core_node13 x:routeFieldColl_shard3_replica_n10] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard3_replica_n10 
url=http://127.0.0.1:56045/solr START 
replicas=[http://127.0.0.1:56047/solr/routeFieldColl_shard3_replica_n8/] 
nUpdates=100
   [junit4]   2> 2417938 INFO  (qtp510448278-25588) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard3 r:core_node11 x:routeFieldColl_shard3_replica_n8] 
o.a.s.c.S.Request [routeFieldColl_shard3_replica_n8]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2418144 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard4_replica_n12 
url=http://127.0.0.1:56046/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 2418144 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2418144 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
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> 2418144 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2418148 INFO  (qtp167073802-25573) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard4 r:core_node15 x:routeFieldColl_shard4_replica_n12] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56046/solr/routeFieldColl_shard4_replica_n12/ shard4
   [junit4]   2> 2418180 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard2_replica_n4 
url=http://127.0.0.1:56046/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 2418180 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2418180 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 2418180 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2418184 INFO  (qtp167073802-25565) [n:127.0.0.1:56046_solr 
c:routeFieldColl s:shard2 r:core_node7 x:routeFieldColl_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56046/solr/routeFieldColl_shard2_replica_n4/ shard2
   [junit4]   2> 2418203 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.u.PeerSync PeerSync: core=routeFieldColl_shard1_replica_n1 
url=http://127.0.0.1:56047/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 2418203 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2418204 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
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> 2418204 INFO  (qtp510448278-25567) [n:127.0.0.1:56047_solr 
c:routeFieldColl s:shard1 r:core_node3 x:routeFieldColl_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2418208 INFO  (qtp510448278-25567) [n:127.0

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

unit4]   2>     at 
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1154)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1149)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$0(SolrZkClient.java:262)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 2855871 INFO  (jetty-closer-6661-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@15df8a25{/solr,null,UNAVAILABLE}
   [junit4]   2> 2855872 ERROR 
(SUITE-TestPullReplica-seed#[232C19C1B0204702]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2855872 INFO  
(SUITE-TestPullReplica-seed#[232C19C1B0204702]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:57443 57443
   [junit4]   2> 2855879 INFO  (Thread-4831) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:57443 57443
   [junit4]   2> 2856031 WARN  (Thread-4831) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        19      /solr/configs/conf
   [junit4]   2>        5       /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        32      
/solr/collections/pull_replica_test_create_delete/state.json
   [junit4]   2>        25      
/solr/collections/pull_replica_test_remove_all_writer_replicas/state.json
   [junit4]   2>        21      
/solr/collections/pull_replica_test_add_remove_pull_replica/state.json
   [junit4]   2>        21      
/solr/collections/pull_replica_test_kill_pull_replica/state.json
   [junit4]   2>        18      
/solr/collections/pull_replica_test_kill_leader/state.json
   [junit4]   2>        13      
/solr/collections/pull_replica_test_add_docs/state.json
   [junit4]   2>        13      
/solr/collections/pull_replica_test_real_time_get/state.json
   [junit4]   2>        5       /solr/clusterstate.json
   [junit4]   2>        5       /solr/clusterprops.json
   [junit4]   2>        3       
/solr/overseer_elect/election/98915817774448646-127.0.0.1:57446_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/live_nodes
   [junit4]   2>        5       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_232C19C1B0204702-001
   [junit4]   2> Oct 30, 2017 3:55:14 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{foo=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
foo_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
id=PostingsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=1947, 
maxMBSortInHeap=7.8443568056578465, sim=RandomSimilarity(queryNorm=false): {}, 
locale=tr, timezone=America/Bahia
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=304078400,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrConfigHandlerCloud, 
TestSolrCoreProperties, AutoAddReplicasIntegrationTest, TestXIncludeConfig, 
TestSolrFieldCacheBean, SolrCloudExampleTest, TestPhraseSuggestions, 
HdfsTlogReplayBufferedWhileIndexingTest, ClusterStateUpdateTest, 
CollectionReloadTest, ZkCLITest, TestShardHandlerFactory, 
DistribJoinFromCollectionTest, TestConfigSetsAPIExclusivity, 
SolrJmxReporterCloudTest, CleanupOldIndexTest, ReturnFieldsTest, 
TestEmbeddedSolrServerAdminHandler, TestNumericRangeQuery32, DeleteShardTest, 
TestDefaultStatsCache, BinaryUpdateRequestHandlerTest, RegexBoostProcessorTest, 
SortByFunctionTest, ChaosMonkeySafeLeaderWithPullReplicasTest, 
UniqFieldsUpdateProcessorFactoryTest, LeaderElectionTest, 
TestManagedSchemaThreadSafety, TestHighlightDedupGrouping, TestJmxIntegration, 
TestCorePropertiesReload, MetricsHandlerTest, TestUtils, 
CollectionTooManyReplicasTest, TestMaxTokenLenTokenizer, 
TestConfigSetProperties, TestRecovery, SuggesterTSTTest, ExecutePlanActionTest, 
MultiThreadedOCPTest, DistribCursorPagingTest, TestCSVResponseWriter, 
HttpSolrCallGetCoreTest, OverseerTaskQueueTest, TestDFRSimilarityFactory, 
TestLeaderInitiatedRecoveryThread, CdcrReplicationHandlerTest, 
TestRetrieveFieldsOptimizer, TestFieldCacheReopen, ExplicitHLLTest, 
HdfsRecoveryZkTest, FastVectorHighlighterTest, TestSweetSpotSimilarityFactory, 
ShardSplitTest, TestClusterStateMutator, AlternateDirectoryTest, 
TriggerIntegrationTest, LeaderFailureAfterFreshStartTest, UUIDFieldTest, 
HdfsBasicDistributedZkTest, SolrShardReporterTest, BasicDistributedZkTest, 
TestCodecSupport, TestUpdate, TolerantUpdateProcessorTest, 
TestReversedWildcardFilterFactory, XsltUpdateRequestHandlerTest, 
JvmMetricsTest, TestSolr4Spatial, TestSortByMinMaxFunction, 
TestStressCloudBlindAtomicUpdates, TestFileDictionaryLookup, TimeZoneUtilsTest, 
SuggesterWFSTTest, TestLockTree, CdcrVersionReplicationTest, TestReqParamsAPI, 
XmlUpdateRequestHandlerTest, DistributedQueryElevationComponentTest, 
TestOmitPositions, TestSkipOverseerOperations, SpellCheckComponentTest, 
DistributedSpellCheckComponentTest, HdfsSyncSliceTest, TestLegacyFieldReuse, 
MultiTermTest, DateRangeFieldTest, DisMaxRequestHandlerTest, 
SystemLogListenerTest, TestSQLHandler, ReplicaListTransformerTest, 
SparseHLLTest, RequestHandlersTest, DistributedFacetPivotSmallAdvancedTest, 
TestLeaderElectionWithEmptyReplica, TestSolrCloudWithHadoopAuthPlugin, 
OverseerTest, TestAtomicUpdateErrorCases, TestDelegationWithHadoopAuth, 
TestAuthenticationFramework, TestRestManager, ResponseLogComponentTest, 
TestFieldSortValues, V2StandaloneTest, TestMaxScoreQueryParser, 
TestManagedSchema, TestClusterProperties, NotRequiredUniqueKeyTest, 
RequestLoggingTest, RecoveryZkTest, EnumFieldTest, TestJsonRequest, 
TestTolerantUpdateProcessorCloud, MoveReplicaHDFSTest, TestNumericTokenStream, 
TestXmlQParser, FieldAnalysisRequestHandlerTest, TestFuzzyAnalyzedSuggestions, 
TestCollectionsAPIViaSolrCloudCluster, TestIBSimilarityFactory, 
TestJavabinTupleStreamParser, TestShortCircuitedRequests, TestReloadDeadlock, 
SolrGangliaReporterTest, TestSchemaResource, HighlighterTest, 
TestManagedResource, BlockDirectoryTest, TestCollapseQParserPlugin, 
TestTolerantSearch, TestAnalyzeInfixSuggestions, SpatialFilterTest, 
CachingDirectoryFactoryTest, TestFieldCacheSortRandom, SecurityConfHandlerTest, 
TestExactSharedStatsCache, TestCryptoKeys, HLLSerializationTest, 
TestManagedSynonymFilterFactory, TestStreamBody, 
DistributedExpandComponentTest, TestAuthorizationFramework, HdfsDirectoryTest, 
TestPseudoReturnFields, BasicAuthStandaloneTest, TestCloudPivotFacet, 
TestSchemaManager, CoreAdminCreateDiscoverTest, HighlighterConfigTest, 
BlockJoinFacetDistribTest, TestBlobHandler, TestMacros, 
PreAnalyzedUpdateProcessorTest, TestSolr4Spatial2, TestGraphTermsQParserPlugin, 
DistributedVersionInfoTest, TestRealTimeGet, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, ChangedSchemaMergeTest, 
TestExpandComponent, StatsComponentTest, TestDistribDocBasedVersion, 
SolrIndexMetricsTest, TestBinaryField, TestFiltering, QueryParsingTest, 
ReplaceNodeTest, SliceStateTest, AddSchemaFieldsUpdateProcessorFactoryTest, 
UnloadDistributedZkTest, TestCoreDiscovery, TestQuerySenderListener, 
WordBreakSolrSpellCheckerTest, TestHighFrequencyDictionaryFactory, 
TestDynamicFieldResource, TestIndexingPerformance, TestStressRecovery, 
DateFieldTest, ConvertedLegacyTest, MinimalSchemaTest, TestRandomFlRTGCloud, 
CircularListTest, TestSolrConfigHandler, 
LeaderInitiatedRecoveryOnShardRestartTest, TestFieldResource, 
TestRandomFaceting, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, 
ShardRoutingCustomTest, TestDistributedSearch, DistributedTermsComponentTest, 
TestCoreContainer, SimpleFacetsTest, SolrCoreTest, QueryElevationComponentTest, 
BadIndexSchemaTest, BasicFunctionalityTest, TestLazyCores, SoftAutoCommitTest, 
PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, 
SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, 
ClusterStateTest, TestSolrJ, TestDocumentBuilder, ZkNodePropsTest, 
FileUtilsTest, DistributedMLTComponentTest, CursorPagingTest, 
DistributedIntervalFacetingTest, SolrTestCaseJ4Test, 
TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, 
TestSimpleTrackingShardHandler, TestLuceneIndexBackCompat, 
TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, AliasIntegrationTest, 
AsyncCallRequestStatusResponseTest, CdcrBootstrapTest, 
CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, ConnectionManagerTest, 
CreateCollectionCleanupTest, CustomCollectionTest, 
LeaderElectionContextKeyTest, MigrateRouteKeyTest, MissingSegmentRecoveryTest, 
MoveReplicaHDFSFailoverTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, 
OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, 
OverseerStatusTest, PeerSyncReplicationTest, SSLMigrationTest, 
SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, 
SolrXmlInZkTest, TestCloudDeleteByQuery, TestCollectionAPI, TestConfigSetsAPI, 
TestOnReconnectListenerSupport, TestPullReplica]
   [junit4] Completed [544/745 (2!)] on J0 in 116.86s, 10 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 47949 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to