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

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

Error Message:
Collection not found: withShardField

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


FAILED:  org.apache.solr.cloud.PeerSyncReplicationTest.test

Error Message:
timeout waiting to see all nodes active

Stack Trace:
java.lang.AssertionError: timeout waiting to see all nodes active
        at 
__randomizedtesting.SeedInfo.seed([A23057AEB5080A8B:2A6468741BF46773]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.waitTillNodesActive(PeerSyncReplicationTest.java:326)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:277)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.forceNodeFailureAndDoPeerSync(PeerSyncReplicationTest.java:259)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:138)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11649 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/init-core-data-001
   [junit4]   2> 1750172 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1750176 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-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> 1750177 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001
   [junit4]   2> 1750177 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1750177 INFO  (Thread-2364) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1750177 INFO  (Thread-2364) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1750287 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:52870
   [junit4]   2> 1750318 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1750318 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1750318 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1750319 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1750322 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@63b610e2{/solr,null,AVAILABLE}
   [junit4]   2> 1750323 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@833de9d{/solr,null,AVAILABLE}
   [junit4]   2> 1750324 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@147171d2{HTTP/1.1,[http/1.1]}{127.0.0.1:52872}
   [junit4]   2> 1750324 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.e.j.s.Server Started @1756626ms
   [junit4]   2> 1750324 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=52872}
   [junit4]   2> 1750324 ERROR (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1750324 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1256471b{/solr,null,AVAILABLE}
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3cd0af62{HTTP/1.1,[http/1.1]}{127.0.0.1:52874}
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.e.j.s.Server Started @1756628ms
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-12T00:08:51.683Z
   [junit4]   2> 1750325 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=52874}
   [junit4]   2> 1750326 ERROR (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1750326 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1750326 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1750326 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1750326 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-12T00:08:51.684Z
   [junit4]   2> 1750329 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@55355f64{/solr,null,AVAILABLE}
   [junit4]   2> 1750330 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2a7bf5a8{HTTP/1.1,[http/1.1]}{127.0.0.1:52875}
   [junit4]   2> 1750330 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.e.j.s.Server Started @1756633ms
   [junit4]   2> 1750331 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@c4643d8{HTTP/1.1,[http/1.1]}{127.0.0.1:52873}
   [junit4]   2> 1750331 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.e.j.s.Server Started @1756634ms
   [junit4]   2> 1750331 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=52875}
   [junit4]   2> 1750332 ERROR (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1750332 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=52873}
   [junit4]   2> 1750333 ERROR (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1750333 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1750333 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1750333 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1750333 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-12T00:08:51.691Z
   [junit4]   2> 1750334 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 1750334 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1750334 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1750334 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-12T00:08:51.692Z
   [junit4]   2> 1750338 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1750338 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1750339 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1750340 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1750353 INFO  (jetty-launcher-2834-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52870/solr
   [junit4]   2> 1750355 INFO  (jetty-launcher-2834-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52870/solr
   [junit4]   2> 1750357 INFO  (jetty-launcher-2834-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52870/solr
   [junit4]   2> 1750360 INFO  (jetty-launcher-2834-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52870/solr
   [junit4]   2> 1750430 INFO  (jetty-launcher-2834-thread-1) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:52875_solr
   [junit4]   2> 1750432 INFO  (jetty-launcher-2834-thread-3) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52873_solr
   [junit4]   2> 1750432 INFO  (jetty-launcher-2834-thread-1) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.Overseer Overseer 
(id=97442734508474377-127.0.0.1:52875_solr-n_0000000000) starting
   [junit4]   2> 1750433 INFO  (jetty-launcher-2834-thread-4) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52874_solr
   [junit4]   2> 1750436 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1750437 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1750438 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1750438 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1750441 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1750441 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1750442 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1750443 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1750463 INFO  (jetty-launcher-2834-thread-1) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52875_solr
   [junit4]   2> 1750467 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1750467 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1750468 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1750468 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1750472 INFO  (jetty-launcher-2834-thread-2) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:52872_solr
   [junit4]   2> 1750478 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1750478 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1750478 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1750479 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1750556 INFO  (jetty-launcher-2834-thread-3) 
[n:127.0.0.1:52873_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node3/.
   [junit4]   2> 1750563 INFO  (jetty-launcher-2834-thread-1) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node1/.
   [junit4]   2> 1750570 INFO  (jetty-launcher-2834-thread-4) 
[n:127.0.0.1:52874_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node4/.
   [junit4]   2> 1750621 INFO  (jetty-launcher-2834-thread-2) 
[n:127.0.0.1:52872_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node2/.
   [junit4]   2> 1751909 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1751911 INFO  
(SUITE-CustomCollectionTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52870/solr ready
   [junit4]   2> 1752003 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[A23057AEB5080A8B]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForHashRouter
   [junit4]   2> 1752005 INFO  
(TEST-CustomCollectionTest.testRouteFieldForHashRouter-seed#[A23057AEB5080A8B]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1752010 INFO  (qtp1239476393-13375) [n:127.0.0.1:52874_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&router.field=shard_s&name=routeFieldColl&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1752013 INFO  
(OverseerThreadFactory-5528-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
routeFieldColl
   [junit4]   2> 1752138 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2
   [junit4]   2> 1752138 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2
   [junit4]   2> 1752138 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1752139 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1752139 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1752140 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2
   [junit4]   2> 1752141 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2
   [junit4]   2> 1752144 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2
   [junit4]   2> 1752283 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_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> 1752284 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_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> 1752283 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_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> 1752284 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_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> 1753181 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753181 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753188 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753201 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753202 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753209 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753210 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753225 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1753234 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1753234 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1753236 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1753240 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1753261 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1753280 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1753286 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1753290 WARN  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753291 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753304 WARN  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753304 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753311 WARN  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753312 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753313 WARN  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753314 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753316 WARN  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard3_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753317 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753336 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753336 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard4_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node2/routeFieldColl_shard4_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node2/./routeFieldColl_shard4_replica2/data/]
   [junit4]   2> 1753338 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753338 WARN  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard4_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753338 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard1_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node1/routeFieldColl_shard1_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node1/./routeFieldColl_shard1_replica2/data/]
   [junit4]   2> 1753339 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753339 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753339 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard3_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node1/routeFieldColl_shard3_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node1/./routeFieldColl_shard3_replica2/data/]
   [junit4]   2> 1753340 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753340 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard2_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node4/routeFieldColl_shard2_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node4/./routeFieldColl_shard2_replica1/data/]
   [junit4]   2> 1753342 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard3_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753342 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard3_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node3/routeFieldColl_shard3_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node3/./routeFieldColl_shard3_replica1/data/]
   [junit4]   2> 1753349 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1753350 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard4_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753350 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard4_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node4/routeFieldColl_shard4_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node4/./routeFieldColl_shard4_replica1/data/]
   [junit4]   2> 1753360 WARN  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema [routeFieldColl_shard2_replica2] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753361 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753362 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard2_replica2' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753363 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.c.SolrCore 
[[routeFieldColl_shard2_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node2/routeFieldColl_shard2_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node2/./routeFieldColl_shard2_replica2/data/]
   [junit4]   2> 1753377 WARN  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema [routeFieldColl_shard1_replica1] default search field in 
schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1753378 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1753379 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'routeFieldColl_shard1_replica1' using 
configuration from collection routeFieldColl
   [junit4]   2> 1753380 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.c.SolrCore 
[[routeFieldColl_shard1_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node3/routeFieldColl_shard1_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A23057AEB5080A8B-001/tempDir-001/node3/./routeFieldColl_shard1_replica1/data/]
   [junit4]   2> 1753499 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753499 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753501 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753501 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753517 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753517 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753519 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753519 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753522 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753522 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753524 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753524 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753534 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753534 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753538 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753538 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753542 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753543 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753546 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@63a3db9[routeFieldColl_shard2_replica1] main]
   [junit4]   2> 1753549 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753549 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753549 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753550 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753550 INFO  
(searcherExecutor-5540-thread-1-processing-n:127.0.0.1:52874_solr 
x:routeFieldColl_shard2_replica1 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:52874_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica1] Registered new searcher 
Searcher@63a3db9[routeFieldColl_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753550 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753550 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753550 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753550 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753550 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753550 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755669291008
   [junit4]   2> 1753551 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753552 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753552 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753552 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753557 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73b63cbe[routeFieldColl_shard3_replica2] main]
   [junit4]   2> 1753562 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753563 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753563 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753563 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1753563 INFO  
(searcherExecutor-5539-thread-1-processing-n:127.0.0.1:52875_solr 
x:routeFieldColl_shard3_replica2 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:52875_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica2] Registered new searcher 
Searcher@73b63cbe[routeFieldColl_shard3_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753563 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755682922496
   [junit4]   2> 1753567 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5efed849[routeFieldColl_shard1_replica2] main]
   [junit4]   2> 1753570 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1753570 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1753570 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753570 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753571 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753571 INFO  
(searcherExecutor-5538-thread-1-processing-n:127.0.0.1:52875_solr 
x:routeFieldColl_shard1_replica2 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:52875_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica2] Registered new searcher 
Searcher@5efed849[routeFieldColl_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753571 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755691311104
   [junit4]   2> 1753574 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1753574 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1753575 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30d1dc6f[routeFieldColl_shard4_replica1] main]
   [junit4]   2> 1753575 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard3: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1753577 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753578 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753578 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753578 INFO  
(searcherExecutor-5542-thread-1-processing-n:127.0.0.1:52874_solr 
x:routeFieldColl_shard4_replica1 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:52874_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica1] Registered new searcher 
Searcher@30d1dc6f[routeFieldColl_shard4_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753578 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755698651136
   [junit4]   2> 1753580 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4b82a11b[routeFieldColl_shard3_replica1] main]
   [junit4]   2> 1753583 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753583 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@77712410[routeFieldColl_shard4_replica2] main]
   [junit4]   2> 1753584 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753584 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753584 INFO  
(searcherExecutor-5541-thread-1-processing-n:127.0.0.1:52873_solr 
x:routeFieldColl_shard3_replica1 s:shard3 c:routeFieldColl) 
[n:127.0.0.1:52873_solr c:routeFieldColl s:shard3  
x:routeFieldColl_shard3_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard3_replica1] Registered new searcher 
Searcher@4b82a11b[routeFieldColl_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753584 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755704942592
   [junit4]   2> 1753586 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753587 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753587 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753587 INFO  
(searcherExecutor-5537-thread-1-processing-n:127.0.0.1:52872_solr 
x:routeFieldColl_shard4_replica2 s:shard4 c:routeFieldColl) 
[n:127.0.0.1:52872_solr c:routeFieldColl s:shard4  
x:routeFieldColl_shard4_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard4_replica2] Registered new searcher 
Searcher@77712410[routeFieldColl_shard4_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753588 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755709136896
   [junit4]   2> 1753589 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard4: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1753593 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9998ms
   [junit4]   2> 1753594 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@f1b1294[routeFieldColl_shard1_replica1] main]
   [junit4]   2> 1753596 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753596 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3bc1cf54[routeFieldColl_shard2_replica2] main]
   [junit4]   2> 1753597 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753597 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753597 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755718574080
   [junit4]   2> 1753597 INFO  
(searcherExecutor-5544-thread-1-processing-n:127.0.0.1:52873_solr 
x:routeFieldColl_shard1_replica1 s:shard1 c:routeFieldColl) 
[n:127.0.0.1:52873_solr c:routeFieldColl s:shard1  
x:routeFieldColl_shard1_replica1] o.a.s.c.SolrCore 
[routeFieldColl_shard1_replica1] Registered new searcher 
Searcher@f1b1294[routeFieldColl_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753598 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1753598 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1753598 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1753598 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1559083755719622656
   [junit4]   2> 1753598 INFO  
(searcherExecutor-5543-thread-1-processing-n:127.0.0.1:52872_solr 
x:routeFieldColl_shard2_replica2 s:shard2 c:routeFieldColl) 
[n:127.0.0.1:52872_solr c:routeFieldColl s:shard2  
x:routeFieldColl_shard2_replica2] o.a.s.c.SolrCore 
[routeFieldColl_shard2_replica2] Registered new searcher 
Searcher@3bc1cf54[routeFieldColl_shard2_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1753699 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_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> 1753699 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_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> 1753700 INFO  
(zkCallback-2852-thread-3-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_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> 1753699 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_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> 1754075 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1754075 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1754075 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:52874/solr/routeFieldColl_shard2_replica1/
   [junit4]   2> 1754075 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica1 url=http://127.0.0.1:52874/solr 
START replicas=[http://127.0.0.1:52872/solr/routeFieldColl_shard2_replica2/] 
nUpdates=100
   [junit4]   2> 1754076 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard2_replica1 url=http://127.0.0.1:52874/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1754076 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1754076 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1754077 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:52875/solr/routeFieldColl_shard3_replica2/
   [junit4]   2> 1754077 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica2 url=http://127.0.0.1:52875/solr 
START replicas=[http://127.0.0.1:52873/solr/routeFieldColl_shard3_replica1/] 
nUpdates=100
   [junit4]   2> 1754077 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard3_replica2 url=http://127.0.0.1:52875/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1754078 INFO  (qtp582900176-13360) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.c.S.Request [routeFieldColl_shard2_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1754079 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1754079 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1754080 INFO  (qtp967218453-13370) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3 r:core_node3 x:routeFieldColl_shard3_replica1] 
o.a.s.c.S.Request [routeFieldColl_shard3_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1754080 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1754080 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1754087 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52874/solr/routeFieldColl_shard2_replica1/ shard2
   [junit4]   2> 1754088 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52875/solr/routeFieldColl_shard3_replica2/ shard3
   [junit4]   2> 1754090 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1754090 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1754090 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:52874/solr/routeFieldColl_shard4_replica1/
   [junit4]   2> 1754091 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica1 url=http://127.0.0.1:52874/solr 
START replicas=[http://127.0.0.1:52872/solr/routeFieldColl_shard4_replica2/] 
nUpdates=100
   [junit4]   2> 1754091 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard4_replica1 url=http://127.0.0.1:52874/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1754093 INFO  (qtp582900176-13361) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4 r:core_node7 x:routeFieldColl_shard4_replica2] 
o.a.s.c.S.Request [routeFieldColl_shard4_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1754093 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1754093 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1754094 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1754094 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1754094 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:52875/solr/routeFieldColl_shard1_replica2/
   [junit4]   2> 1754095 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard1_replica2 url=http://127.0.0.1:52875/solr 
START replicas=[http://127.0.0.1:52873/solr/routeFieldColl_shard1_replica1/] 
nUpdates=100
   [junit4]   2> 1754095 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] o.a.s.u.PeerSync 
PeerSync: core=routeFieldColl_shard1_replica2 url=http://127.0.0.1:52875/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1754095 INFO  (qtp967218453-13371) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.c.S.Request [routeFieldColl_shard1_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1754096 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1754096 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1754102 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52874/solr/routeFieldColl_shard4_replica1/ shard4
   [junit4]   2> 1754103 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:52875/solr/routeFieldColl_shard1_replica2/ shard1
   [junit4]   2> 1754218 INFO  
(zkCallback-2852-thread-2-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_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> 1754218 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_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> 1754218 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_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> 1754218 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_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> 1754261 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1754262 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1754262 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1754261 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1754266 INFO  (qtp1239476393-13379) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2}
 status=0 QTime=2126
   [junit4]   2> 1754266 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2127
   [junit4]   2> 1754267 INFO  (qtp1239476393-13377) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2}
 status=0 QTime=2128
   [junit4]   2> 1754267 INFO  (qtp1958637594-13385) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2}
 status=0 QTime=2125
   [junit4]   2> 1754387 INFO  
(zkCallback-2852-thread-1-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_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> 1754387 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_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> 1754387 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_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> 1754387 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_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> 1754607 INFO  (qtp582900176-13357) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4  x:routeFieldColl_shard4_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard4_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard4&wt=javabin&version=2}
 status=0 QTime=2463
   [junit4]   2> 1754607 INFO  (qtp967218453-13366) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3  x:routeFieldColl_shard3_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard3_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard3&wt=javabin&version=2}
 status=0 QTime=2468
   [junit4]   2> 1754612 INFO  (qtp967218453-13368) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1  x:routeFieldColl_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard1_replica1&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2472
   [junit4]   2> 1754612 INFO  (qtp582900176-13355) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2  x:routeFieldColl_shard2_replica2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=routeFieldColl_shard2_replica2&action=CREATE&numShards=4&collection=routeFieldColl&shard=shard2&wt=javabin&version=2}
 status=0 QTime=2472
   [junit4]   2> 1754618 INFO  (qtp1239476393-13375) [n:127.0.0.1:52874_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 1754733 INFO  
(zkCallback-2855-thread-1-processing-n:127.0.0.1:52874_solr) 
[n:127.0.0.1:52874_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> 1754733 INFO  
(zkCallback-2853-thread-1-processing-n:127.0.0.1:52873_solr) 
[n:127.0.0.1:52873_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> 1754733 INFO  
(zkCallback-2852-thread-3-processing-n:127.0.0.1:52875_solr) 
[n:127.0.0.1:52875_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> 1754733 INFO  
(zkCallback-2854-thread-1-processing-n:127.0.0.1:52872_solr) 
[n:127.0.0.1:52872_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> 1755619 INFO  (qtp1239476393-13375) [n:127.0.0.1:52874_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&router.field=shard_s&name=routeFieldColl&action=CREATE&numShards=4&wt=javabin&version=2}
 status=0 QTime=3609
   [junit4]   2> 1755631 INFO  (qtp967218453-13367) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3 r:core_node3 x:routeFieldColl_shard3_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52875/solr/routeFieldColl_shard3_replica2/&wt=javabin&version=2}{add=[6
 (1559083757844037632), 7 (1559083757848231936)]} 0 2
   [junit4]   2> 1755670 INFO  (qtp1958637594-13386) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3 r:core_node8 x:routeFieldColl_shard3_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard3_replica2]  
webapp=/solr path=/update 
params={_stateVer_=routeFieldColl:5&wt=javabin&version=2}{add=[6 
(1559083757844037632), 7 (1559083757848231936)]} 0 46
   [junit4]   2> 1755670 INFO  (qtp967218453-13369) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:52875/solr/routeFieldColl_shard1_replica2/&wt=javabin&version=2}{add=[8
 (1559083757844037632)]} 0 40
   [junit4]   2> 1755671 INFO  (qtp1958637594-13388) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard1_replica2]  
webapp=/solr path=/update 
params={_stateVer_=routeFieldColl:5&wt=javabin&version=2}{add=[8 
(1559083757844037632)]} 0 47
   [junit4]   2> 1755672 INFO  (qtp967218453-13370) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755672 INFO  (qtp967218453-13370) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard1 r:core_node4 x:routeFieldColl_shard1_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@49578ee
   [junit4]   2> 1755673 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755673 INFO  (qtp1958637594-13384) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard1 r:core_node5 x:routeFieldColl_shard1_replica2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2320d084
   [junit4]   2> 1755673 INFO  (qtp582900176-13362) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755673 INFO  (qtp582900176-13362) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1755675 INFO  (qtp1239476393-13380) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2 r:core_node2 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755675 INFO  (qtp1239476393-13380) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2 r:core_node2 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1755675 INFO  (qtp582900176-13362) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1755675 INFO  (qtp582900176-13362) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard2 r:core_node6 x:routeFieldColl_shard2_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard2_replica2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:52875/solr/routeFieldColl_shard3_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 1755676 INFO  (qtp967218453-13371) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3 r:core_node3 x:routeFieldColl_shard3_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755676 INFO  (qtp967218453-13371) [n:127.0.0.1:52873_solr 
c:routeFieldColl s:shard3 r:core_node3 x:routeFieldColl_shard3_replica1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4124fbb2
   [junit4]   2> 1755677 INFO  (qtp1239476393-13380) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2 r:core_node2 x:routeFieldColl_shard2_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1755677 INFO  (qtp1239476393-13380) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard2 r:core_node2 x:routeFieldColl_shard2_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [routeFieldColl_shard2_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:52875/solr/routeFieldColl_shard3_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 1755677 INFO  (qtp1239476393-13381) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4 r:core_node1 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755678 INFO  (qtp1239476393-13381) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4 r:core_node1 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1755679 INFO  (qtp1239476393-13381) [n:127.0.0.1:52874_solr 
c:routeFieldColl s:shard4 r:core_node1 x:routeFieldColl_shard4_replica1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1755679 INFO  (qtp582900176-13358) [n:127.0.0.1:52872_solr 
c:routeFieldColl s:shard4 r:core_node7 x:routeFieldColl_shard4_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1755679 INFO  (qtp1958637594-13387) [n:127.0.0.1:52875_solr 
c:routeFieldColl s:shard3 r:core_node8 x:routeFieldColl_shard3_replica2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSe

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

junit4]    >    at 
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:138)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 3358094 INFO  
(SUITE-PeerSyncReplicationTest-seed#[A23057AEB5080A8B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_A23057AEB5080A8B-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{other_tl1=PostingsFormat(name=Direct), 
range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), 
rnd_s=PostingsFormat(name=Direct), 
multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 intDefault=PostingsFormat(name=Direct), 
a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 id=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 text=Lucene50(blocksize=128), 
range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
 docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), 
_version_=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
intDvoDefault=DocValuesFormat(name=Lucene70), 
timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=789, 
maxMBSortInHeap=6.54940252975365, sim=RandomSimilarity(queryNorm=false): {}, 
locale=ru-RU, timezone=Asia/Rangoon
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=273524096,total=515244032
   [junit4]   2> NOTE: All tests run in this JVM: [TestUnifiedSolrHighlighter, 
StatsComponentTest, DistributedSuggestComponentTest, 
SignatureUpdateProcessorFactoryTest, UpdateRequestProcessorFactoryTest, 
ChaosMonkeySafeLeaderTest, ParsingFieldUpdateProcessorsTest, 
CoreMergeIndexesAdminHandlerTest, TestBadConfig, TestCrossCoreJoin, 
DateMathParserTest, TestStandardQParsers, 
VMParamsZkACLAndCredentialsProvidersTest, TestExactStatsCache, JsonLoaderTest, 
TestIndexingPerformance, SharedFSAutoReplicaFailoverTest, ScriptEngineTest, 
TestFastWriter, TestConfigSets, TestStressInPlaceUpdates, 
TestClusterStateMutator, SortByFunctionTest, TestManagedResourceStorage, 
HttpPartitionTest, ZkSolrClientTest, TestShortCircuitedRequests, 
TestSolrConfigHandlerCloud, CoreAdminHandlerTest, TestConfigSetImmutable, 
SuggesterTSTTest, FieldAnalysisRequestHandlerTest, CSVRequestHandlerTest, 
OpenCloseCoreStressTest, TestGeoJSONResponseWriter, TestExceedMaxTermLength, 
BasicAuthIntegrationTest, SolrGangliaReporterTest, TestPKIAuthenticationPlugin, 
TestReloadAndDeleteDocs, TestConfigsApi, BlockCacheTest, 
TestLeaderElectionZkExpiry, LeaderFailoverAfterPartitionTest, RuleEngineTest, 
CdcrReplicationDistributedZkTest, TestSchemaManager, BlobRepositoryCloudTest, 
TestSolrCoreProperties, SolrCoreCheckLockOnStartupTest, TestUtils, 
SubstringBytesRefFilterTest, TestSQLHandler, ConjunctionSolrSpellCheckerTest, 
InfixSuggestersTest, TestSubQueryTransformerDistrib, TestCollapseQParserPlugin, 
TestOrdValues, CacheHeaderTest, TestCustomStream, TestSolrQueryParserResource, 
TestSha256AuthenticationProvider, SaslZkACLProviderTest, 
TestComplexPhraseQParserPlugin, TestSerializedLuceneMatchVersion, 
SpellingQueryConverterTest, TestManagedStopFilterFactory, 
TestFieldTypeCollectionResource, TestSolrDeletionPolicy2, 
TestRawResponseWriter, TestTestInjection, TestDistribIDF, 
TestDFRSimilarityFactory, SolrCmdDistributorTest, DeleteReplicaTest, 
TestMiniSolrCloudCluster, TestCloudInspectUtil, AutoCommitTest, 
TestComponentsName, LeaderInitiatedRecoveryOnCommitTest, 
CollectionsAPIAsyncDistributedZkTest, TestAtomicUpdateErrorCases, 
PKIAuthenticationIntegrationTest, TestJoin, HLLUtilTest, TestCoreDiscovery, 
TestUninvertingReader, RankQueryTest, TestManagedSchema, 
RAMDirectoryFactoryTest, TestSolrCloudWithDelegationTokens, 
TestScoreJoinQPScore, NoCacheHeaderTest, TestMiniSolrCloudClusterSSL, 
TestLMDirichletSimilarityFactory, TestDeleteCollectionOnDownNodes, 
TestBulkSchemaAPI, CoreAdminCreateDiscoverTest, BasicDistributedZk2Test, 
RegexBoostProcessorTest, TestSolr4Spatial, CachingDirectoryFactoryTest, 
MigrateRouteKeyTest, ClassificationUpdateProcessorFactoryTest, 
TestLuceneMatchVersion, TestJsonFacets, CdcrRequestHandlerTest, 
FieldMutatingUpdateProcessorTest, BadIndexSchemaTest, TestUseDocValuesAsStored, 
TermVectorComponentDistributedTest, DefaultValueUpdateProcessorTest, 
ResponseLogComponentTest, TestDocSet, SuggesterTest, DirectoryFactoryTest, 
BigEndianAscendingWordDeserializerTest, GraphQueryTest, 
DirectUpdateHandlerOptimizeTest, TestJettySolrRunner, ReplicationFactorTest, 
CursorMarkTest, HighlighterConfigTest, TestMaxScoreQueryParser, 
DocValuesMissingTest, DistributedFacetPivotSmallTest, TestZkChroot, 
SegmentsInfoRequestHandlerTest, TestSchemaSimilarityResource, 
SolrMetricReporterTest, TestTrieFacet, DistributedIntervalFacetingTest, 
TestReload, TestIndexSearcher, DeleteStatusTest, TestRecoveryHdfs, 
TestImplicitCoreProperties, TestXmlQParserPlugin, TestValueSourceCache, 
TestHdfsBackupRestoreCore, DistributedFacetExistsSmallTest, 
TestSolrCoreSnapshots, TestConfigSetsAPI, CustomCollectionTest, 
TestSchemaNameResource, LukeRequestHandlerTest, ShardRoutingCustomTest, 
TestIntervalFaceting, IndexSchemaTest, ShowFileRequestHandlerTest, 
FileBasedSpellCheckerTest, MinimalSchemaTest, BitVectorTest, TestRTimerTree, 
AnalysisErrorHandlingTest, TestSolrQueryParser, 
TestCopyFieldCollectionResource, OverseerStatusTest, 
OverriddenZkACLAndCredentialsProvidersTest, TestQueryTypes, 
TestSSLRandomization, HdfsBasicDistributedZkTest, 
TestLMJelinekMercerSimilarityFactory, TestRandomFaceting, UpdateParamsTest, 
TestAnalyzedSuggestions, BlockJoinFacetRandomTest, TestSystemIdResolver, 
SolrCloudExampleTest, TestWriterPerf, TlogReplayBufferedWhileIndexingTest, 
UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, 
LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, 
LeaderElectionTest, TestDistributedGrouping, 
DistributedSpellCheckComponentTest, ZkControllerTest, HardAutoCommitTest, 
TestRangeQuery, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, 
SpellCheckCollatorTest, SuggesterFSTTest, SolrRequestParserTest, 
TestFoldingMultitermQuery, DocValuesTest, 
StatelessScriptUpdateProcessorFactoryTest, SolrInfoMBeanTest, 
IndexBasedSpellCheckerTest, StandardRequestHandlerTest, 
DocumentAnalysisRequestHandlerTest, TestOmitPositions, 
XmlUpdateRequestHandlerTest, RequestHandlersTest, 
PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, LoggingHandlerTest, 
TestJmxIntegration, TestPartialUpdateDeduplication, TestLFUCache, 
TestMergePolicyConfig, MultiTermTest, TestSearchPerf, TestConfig, 
TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, 
NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestCharFilters, 
TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestIBSimilarityFactory, 
TestBM25SimilarityFactory, TestJmxMonitoredMap, ResourceLoaderTest, 
TestFastOutputStream, URLClassifyProcessorTest, PluginInfoTest, 
PreAnalyzedFieldTest, PrimUtilsTest, TestSuggestSpellingConverter, 
DateFieldTest, ClusterStateTest, TestSolrJ, TestLRUCache, SliceStateTest, 
UUIDFieldTest, FileUtilsTest, CircularListTest, TestRTGBase, 
SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, 
TestSimpleTrackingShardHandler, AssignTest, BaseCdcrDistributedZkTest, 
CleanupOldIndexTest, CollectionReloadTest, CollectionTooManyReplicasTest, 
ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, 
CreateCollectionCleanupTest, DeleteShardTest, DistribJoinFromCollectionTest, 
DistributedQueueTest, ForceLeaderTest, PeerSyncReplicationTest]
   [junit4] Completed [510/694 (2!)] on J1 in 219.07s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 62792 lines...]
-documentation-lint:
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build/jtidy_tmp
     [echo] Checking for broken links...
     [exec] 
     [exec] Crawl/parse...
     [exec] 
     [exec] Verify...
     [echo] Checking for malformed docs...
     [exec] 
     [exec] 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/docs/solr-solrj/overview-summary.html
     [exec]   missing: org.apache.solr.client.solrj.io.eval
     [exec] 
     [exec] Missing javadocs were found!

BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:775: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:101: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:654: The 
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:670: The 
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:2493:
 exec returned: 1

Total time: 119 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any


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

Reply via email to