Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/212/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseParallelGC 
--illegal-access=deny

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([9F0845C474AC9EED:CA58AD56D855511D]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1139)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:822)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
        at 
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:141)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.cloud.TestTlogReplica.testRecovery

Error Message:
Can not find doc 3 in https://127.0.0.1:59262/solr

Stack Trace:
java.lang.AssertionError: Can not find doc 3 in https://127.0.0.1:59262/solr
        at 
__randomizedtesting.SeedInfo.seed([9F0845C474AC9EED:5EF83C6859FC544A]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:868)
        at 
org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:559)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 13045 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/init-core-data-001
   [junit4]   2> 2207918 WARN  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
   [junit4]   2> 2207918 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2207918 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 2207918 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2207919 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001
   [junit4]   2> 2207919 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2207919 INFO  (Thread-4897) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2207919 INFO  (Thread-4897) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2207922 ERROR (Thread-4897) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2208029 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:57589
   [junit4]   2> 2208050 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2208050 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2208051 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2208051 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2208054 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@18db9284{/solr,null,AVAILABLE}
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4d60d16{HTTP/1.1,[http/1.1]}{127.0.0.1:57591}
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.e.j.s.Server Started @2209670ms
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57591}
   [junit4]   2> 2208055 ERROR (jetty-launcher-4494-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2208055 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T01:50:50.641971Z
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7a5340f{/solr,null,AVAILABLE}
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@150649c5{HTTP/1.1,[http/1.1]}{127.0.0.1:57592}
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.e.j.s.Server Started @2209671ms
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57592}
   [junit4]   2> 2208056 ERROR (jetty-launcher-4494-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2208056 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2208057 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T01:50:50.643044Z
   [junit4]   2> 2208059 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1de089a9{/solr,null,AVAILABLE}
   [junit4]   2> 2208059 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d540486{HTTP/1.1,[http/1.1]}{127.0.0.1:57594}
   [junit4]   2> 2208059 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.e.j.s.Server Started @2209674ms
   [junit4]   2> 2208059 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57594}
   [junit4]   2> 2208059 ERROR (jetty-launcher-4494-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2208060 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2208060 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2208060 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2208060 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T01:50:50.646615Z
   [junit4]   2> 2208062 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@29cc901f{/solr,null,AVAILABLE}
   [junit4]   2> 2208063 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6e5edbe9{HTTP/1.1,[http/1.1]}{127.0.0.1:57595}
   [junit4]   2> 2208063 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.e.j.s.Server Started @2209678ms
   [junit4]   2> 2208063 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57595}
   [junit4]   2> 2208064 ERROR (jetty-launcher-4494-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2208064 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 2208064 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2208064 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 2208064 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-28T01:50:50.650897Z
   [junit4]   2> 2208067 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2208068 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2208078 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2208078 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2208089 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57589/solr
   [junit4]   2> 2208091 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57589/solr
   [junit4]   2> 2208097 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57589/solr
   [junit4]   2> 2208103 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57589/solr
   [junit4]   2> 2208182 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2208185 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2208187 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57594_solr
   [junit4]   2> 2208192 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=98734142292426764-127.0.0.1:57594_solr-n_0000000000) starting
   [junit4]   2> 2208194 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57591_solr
   [junit4]   2> 2208200 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2208200 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2208250 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57594_solr
   [junit4]   2> 2208253 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2208254 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2208254 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2208263 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2208266 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2208266 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2208269 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57595_solr
   [junit4]   2> 2208270 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57592_solr
   [junit4]   2> 2208272 INFO  (zkCallback-4510-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2208272 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2208273 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2208273 INFO  (zkCallback-4515-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2208307 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57594.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208315 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57591.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208343 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57591.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208343 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57591.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208348 INFO  (jetty-launcher-4494-thread-3) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node3/.
   [junit4]   2> 2208350 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57594.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208350 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57594.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208355 INFO  (jetty-launcher-4494-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node2/.
   [junit4]   2> 2208381 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57595.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208384 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57592.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208429 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57592.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208429 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57595.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208429 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57592.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208429 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57595.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2208430 INFO  (jetty-launcher-4494-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node1/.
   [junit4]   2> 2208430 INFO  (jetty-launcher-4494-thread-4) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node4/.
   [junit4]   2> 2208564 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2208565 INFO  
(SUITE-CustomCollectionTest-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57589/solr ready
   [junit4]   2> 2208587 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[9F0845C474AC9EED]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testCustomCollectionsAPI
   [junit4]   2> 2208588 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[9F0845C474AC9EED]) [  
  ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2208593 INFO  (qtp1224334062-25214) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b,c&replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&name=implicitcoll&router.name=implicit&nrtReplicas=2&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2208598 INFO  (OverseerThreadFactory-11269-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection implicitcoll
   [junit4]   2> 2208719 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"implicitcoll_a_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57595/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208725 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"implicitcoll_a_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57592/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208731 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"implicitcoll_b_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57591/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208737 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"implicitcoll_b_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57594/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208744 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"implicitcoll_c_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57595/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208750 INFO  
(OverseerStateUpdate-98734142292426764-127.0.0.1:57594_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"implicitcoll",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"implicitcoll_c_replica_n10",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:57592/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2208964 INFO  (qtp2099364128-25202) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208965 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2208965 INFO  (qtp1224334062-25215) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n4&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208965 INFO  (qtp2140930447-25211) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208966 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2208966 INFO  (qtp2140930447-25211) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2208966 INFO  (qtp2140930447-25222) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n10&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208967 INFO  (qtp2099364128-25204) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n8&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208970 INFO  (qtp621682900-25232) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n6&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2208970 INFO  (qtp621682900-25232) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2209106 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209106 INFO  (zkCallback-4510-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209106 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209106 INFO  (zkCallback-4515-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209106 INFO  (zkCallback-4514-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209107 INFO  (zkCallback-4516-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209107 INFO  (zkCallback-4515-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2209986 INFO  (qtp2099364128-25202) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210016 INFO  (qtp2140930447-25222) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210025 INFO  (qtp2099364128-25202) [    ] o.a.s.s.IndexSchema 
[implicitcoll_a_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 2210038 INFO  (qtp1224334062-25215) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210050 INFO  (qtp621682900-25232) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210059 INFO  (qtp2140930447-25222) [    ] o.a.s.s.IndexSchema 
[implicitcoll_c_replica_n10] Schema name=cloud-dynamic
   [junit4]   2> 2210064 INFO  (qtp2099364128-25204) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210066 INFO  (qtp621682900-25232) [    ] o.a.s.s.IndexSchema 
[implicitcoll_b_replica_n6] Schema name=cloud-dynamic
   [junit4]   2> 2210106 INFO  (qtp2140930447-25222) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210106 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n10' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210106 INFO  (qtp2140930447-25222) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57592.solr.core.implicitcoll.c.replica_n10' (registry 
'solr.core.implicitcoll.c.replica_n10') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210107 INFO  (qtp2140930447-25222) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210107 INFO  (qtp2140930447-25222) [    ] o.a.s.c.SolrCore 
[[implicitcoll_c_replica_n10] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node4/implicitcoll_c_replica_n10],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node4/./implicitcoll_c_replica_n10/data/]
   [junit4]   2> 2210108 INFO  (qtp1224334062-25215) [    ] o.a.s.s.IndexSchema 
[implicitcoll_b_replica_n4] Schema name=cloud-dynamic
   [junit4]   2> 2210115 INFO  (qtp2140930447-25211) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 2210133 INFO  (qtp2099364128-25202) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210133 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n1' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210133 INFO  (qtp621682900-25232) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210133 INFO  (qtp621682900-25232) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n6' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210134 INFO  (qtp2099364128-25202) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57595.solr.core.implicitcoll.a.replica_n1' (registry 
'solr.core.implicitcoll.a.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210134 INFO  (qtp2099364128-25202) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210134 INFO  (qtp2099364128-25202) [    ] o.a.s.c.SolrCore 
[[implicitcoll_a_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node1/implicitcoll_a_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node1/./implicitcoll_a_replica_n1/data/]
   [junit4]   2> 2210134 INFO  (qtp621682900-25232) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57594.solr.core.implicitcoll.b.replica_n6' (registry 
'solr.core.implicitcoll.b.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210135 INFO  (qtp621682900-25232) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210135 INFO  (qtp621682900-25232) [    ] o.a.s.c.SolrCore 
[[implicitcoll_b_replica_n6] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node2/implicitcoll_b_replica_n6],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node2/./implicitcoll_b_replica_n6/data/]
   [junit4]   2> 2210139 INFO  (qtp2140930447-25211) [    ] o.a.s.s.IndexSchema 
[implicitcoll_a_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 2210139 INFO  (qtp2099364128-25204) [    ] o.a.s.s.IndexSchema 
[implicitcoll_c_replica_n8] Schema name=cloud-dynamic
   [junit4]   2> 2210148 INFO  (qtp1224334062-25215) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210148 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n4' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210157 INFO  (qtp1224334062-25215) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57591.solr.core.implicitcoll.b.replica_n4' (registry 
'solr.core.implicitcoll.b.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210157 INFO  (qtp1224334062-25215) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210157 INFO  (qtp1224334062-25215) [    ] o.a.s.c.SolrCore 
[[implicitcoll_b_replica_n4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node3/implicitcoll_b_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node3/./implicitcoll_b_replica_n4/data/]
   [junit4]   2> 2210169 INFO  (qtp2099364128-25204) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210170 INFO  (qtp2099364128-25204) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n8' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210170 INFO  (qtp2099364128-25204) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57595.solr.core.implicitcoll.c.replica_n8' (registry 
'solr.core.implicitcoll.c.replica_n8') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210170 INFO  (qtp2099364128-25204) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210171 INFO  (qtp2099364128-25204) [    ] o.a.s.c.SolrCore 
[[implicitcoll_c_replica_n8] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node1/implicitcoll_c_replica_n8],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node1/./implicitcoll_c_replica_n8/data/]
   [junit4]   2> 2210180 INFO  (qtp2140930447-25211) [    ] o.a.s.s.IndexSchema 
Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2210180 INFO  (qtp2140930447-25211) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n2' using 
configuration from collection implicitcoll, trusted=true
   [junit4]   2> 2210181 INFO  (qtp2140930447-25211) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57592.solr.core.implicitcoll.a.replica_n2' (registry 
'solr.core.implicitcoll.a.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c845ec7
   [junit4]   2> 2210181 INFO  (qtp2140930447-25211) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2210181 INFO  (qtp2140930447-25211) [    ] o.a.s.c.SolrCore 
[[implicitcoll_a_replica_n2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node4/implicitcoll_a_replica_n2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_9F0845C474AC9EED-001/tempDir-001/node4/./implicitcoll_a_replica_n2/data/]
   [junit4]   2> 2210250 INFO  (qtp2099364128-25202) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210250 INFO  (qtp2099364128-25202) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210252 INFO  (qtp2099364128-25202) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210252 INFO  (qtp2099364128-25202) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210264 INFO  (qtp2099364128-25202) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@78e67867[implicitcoll_a_replica_n1] 
main]
   [junit4]   2> 2210265 INFO  (qtp2099364128-25202) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210266 INFO  (qtp2099364128-25202) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210267 INFO  (qtp2099364128-25202) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210268 INFO  (qtp2099364128-25202) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279139835904
   [junit4]   2> 2210268 INFO  (searcherExecutor-11278-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n1] Registered new searcher 
Searcher@78e67867[implicitcoll_a_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210277 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard a: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2210282 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210282 INFO  (qtp2140930447-25222) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210284 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210284 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210287 INFO  (qtp1224334062-25215) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210287 INFO  (qtp1224334062-25215) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210289 INFO  (qtp1224334062-25215) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210289 INFO  (qtp1224334062-25215) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210296 INFO  (qtp2140930447-25222) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3cb9b2d[implicitcoll_c_replica_n10] 
main]
   [junit4]   2> 2210298 INFO  (qtp2140930447-25222) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210298 INFO  (qtp2140930447-25222) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210299 INFO  (qtp2140930447-25222) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210299 INFO  (qtp2140930447-25222) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279172341760
   [junit4]   2> 2210300 INFO  (searcherExecutor-11277-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_c_replica_n10] Registered new searcher 
Searcher@3cb9b2d[implicitcoll_c_replica_n10] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210305 INFO  (qtp1224334062-25215) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@714df555[implicitcoll_b_replica_n4] 
main]
   [junit4]   2> 2210313 INFO  (qtp621682900-25232) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210313 INFO  (qtp621682900-25232) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210314 INFO  (qtp1224334062-25215) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210315 INFO  (qtp1224334062-25215) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210316 INFO  (qtp1224334062-25215) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210316 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210316 INFO  (qtp2099364128-25204) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210317 INFO  (searcherExecutor-11280-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_b_replica_n4] Registered new searcher 
Searcher@714df555[implicitcoll_b_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210317 INFO  (qtp1224334062-25215) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279191216128
   [junit4]   2> 2210318 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210318 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210321 INFO  (qtp621682900-25232) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210321 INFO  (qtp621682900-25232) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210325 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard b: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2210327 INFO  (qtp2099364128-25204) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a6a79c6[implicitcoll_c_replica_n8] 
main]
   [junit4]   2> 2210329 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard c: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 2210330 INFO  (qtp2099364128-25204) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210331 INFO  (qtp2099364128-25204) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210331 INFO  (qtp621682900-25232) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@45d2adab[implicitcoll_b_replica_n6] 
main]
   [junit4]   2> 2210332 INFO  (qtp2099364128-25204) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210333 INFO  (searcherExecutor-11281-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_c_replica_n8] Registered new searcher 
Searcher@1a6a79c6[implicitcoll_c_replica_n8] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210333 INFO  (qtp2099364128-25204) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279207993344
   [junit4]   2> 2210333 INFO  (qtp621682900-25232) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210334 INFO  (qtp621682900-25232) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210335 INFO  (qtp621682900-25232) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210335 INFO  (searcherExecutor-11279-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_b_replica_n6] Registered new searcher 
Searcher@45d2adab[implicitcoll_b_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210336 INFO  (qtp621682900-25232) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279211139072
   [junit4]   2> 2210342 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2210342 INFO  (qtp2140930447-25211) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2210344 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2210344 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2210346 INFO  (qtp2140930447-25211) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5dc65e25[implicitcoll_a_replica_n2] 
main]
   [junit4]   2> 2210348 INFO  (qtp2140930447-25211) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2210349 INFO  (qtp2140930447-25211) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2210349 INFO  (qtp2140930447-25211) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2210350 INFO  (qtp2140930447-25211) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1579746279225819136
   [junit4]   2> 2210350 INFO  (searcherExecutor-11282-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n2] Registered new searcher 
Searcher@5dc65e25[implicitcoll_a_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2210435 INFO  (zkCallback-4510-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4514-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4516-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4515-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210435 INFO  (zkCallback-4515-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2210788 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2210788 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2210788 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/
   [junit4]   2> 2210789 INFO  (qtp2099364128-25202) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_a_replica_n1 url=http://127.0.0.1:57595/solr START 
replicas=[http://127.0.0.1:57592/solr/implicitcoll_a_replica_n2/] nUpdates=100
   [junit4]   2> 2210790 INFO  (qtp2140930447-25224) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2210826 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2210826 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2210827 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/
   [junit4]   2> 2210827 INFO  (qtp1224334062-25215) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_b_replica_n4 url=http://127.0.0.1:57591/solr START 
replicas=[http://127.0.0.1:57594/solr/implicitcoll_b_replica_n6/] nUpdates=100
   [junit4]   2> 2210829 INFO  (qtp621682900-25234) [    ] o.a.s.c.S.Request 
[implicitcoll_b_replica_n6]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2210837 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2210837 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2210837 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/
   [junit4]   2> 2210838 INFO  (qtp2140930447-25222) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_c_replica_n10 url=http://127.0.0.1:57592/solr START 
replicas=[http://127.0.0.1:57595/solr/implicitcoll_c_replica_n8/] nUpdates=100
   [junit4]   2> 2210839 INFO  (qtp2099364128-25206) [    ] o.a.s.c.S.Request 
[implicitcoll_c_replica_n8]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2211097 INFO  (qtp2099364128-25202) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_a_replica_n1 url=http://127.0.0.1:57595/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 2211097 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2211097 INFO  (qtp2099364128-25202) [    ] 
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> 2211097 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2211106 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/ a
   [junit4]   2> 2211131 INFO  (qtp1224334062-25215) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_b_replica_n4 url=http://127.0.0.1:57591/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 2211131 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2211131 INFO  (qtp1224334062-25215) [    ] 
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> 2211131 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2211143 INFO  (qtp2140930447-25222) [    ] o.a.s.u.PeerSync 
PeerSync: core=implicitcoll_c_replica_n10 url=http://127.0.0.1:57592/solr DONE. 
 We have no versions.  sync failed.
   [junit4]   2> 2211143 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 2211143 INFO  (qtp2140930447-25222) [    ] 
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> 2211143 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2211145 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/ b
   [junit4]   2> 2211152 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/ c
   [junit4]   2> 2211267 INFO  (zkCallback-4510-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4514-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4516-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4515-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211267 INFO  (zkCallback-4515-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211313 INFO  (qtp1224334062-25215) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2211315 INFO  (qtp1224334062-25215) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n4&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2349
   [junit4]   2> 2211316 INFO  (qtp2099364128-25202) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2211316 INFO  (qtp2140930447-25222) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2211318 INFO  (qtp2099364128-25202) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2353
   [junit4]   2> 2211318 INFO  (qtp2140930447-25222) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n10&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2351
   [junit4]   2> 2211365 INFO  (qtp2099364128-25204) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n8&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2398
   [junit4]   2> 2211365 INFO  (qtp621682900-25232) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n6&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2395
   [junit4]   2> 2211368 INFO  (qtp2140930447-25211) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2402
   [junit4]   2> 2211372 INFO  (qtp1224334062-25214) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 2211486 INFO  (zkCallback-4510-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4514-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4514-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4516-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4516-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4515-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2211486 INFO  (zkCallback-4515-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2212382 INFO  (qtp1224334062-25214) [    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={shards=a,b,c&replicationFactor=2&collection.configName=conf&maxShardsPerNode=3&name=implicitcoll&router.name=implicit&nrtReplicas=2&action=CREATE&wt=javabin&version=2}
 status=0 QTime=3788
   [junit4]   2> 2212390 INFO  (qtp2140930447-25225) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&wt=javabin&version=2}{add=[6
 (1579746281359671296), 7 (1579746281359671297), 8 (1579746281359671298)]} 0 4
   [junit4]   2> 2212391 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281365962752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212391 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2d0e169d 
commitCommandVersion:1579746281365962752
   [junit4]   2> 2212392 INFO  (qtp1224334062-25216) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281367011328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212392 INFO  (qtp1224334062-25216) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212392 INFO  (qtp2140930447-25226) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281367011328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212392 INFO  (qtp2140930447-25226) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2556b33b 
commitCommandVersion:1579746281367011328
   [junit4]   2> 2212393 INFO  (qtp621682900-25235) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281368059904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212393 INFO  (qtp621682900-25235) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212393 INFO  (qtp2140930447-25227) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281368059904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212393 INFO  (qtp2140930447-25227) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212393 INFO  (qtp2099364128-25205) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281368059904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212393 INFO  (qtp1224334062-25216) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212393 INFO  (qtp2099364128-25205) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212394 INFO  (qtp1224334062-25216) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 2212394 INFO  (qtp621682900-25235) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212394 INFO  (qtp621682900-25235) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 2212394 INFO  (qtp2140930447-25227) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212394 INFO  (qtp2140930447-25227) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n10]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2212394 INFO  (qtp2099364128-25205) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212394 INFO  (qtp2099364128-25205) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n8]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2212396 INFO  (qtp2140930447-25226) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3346cd5[implicitcoll_a_replica_n2] 
main]
   [junit4]   2> 2212396 INFO  (qtp2099364128-25210) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4a5a94ed[implicitcoll_a_replica_n1] 
main]
   [junit4]   2> 2212397 INFO  (qtp2140930447-25226) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212397 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212398 INFO  (searcherExecutor-11278-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n1] Registered new searcher 
Searcher@4a5a94ed[implicitcoll_a_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C3)))}
   [junit4]   2> 2212399 INFO  (searcherExecutor-11282-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n2] Registered new searcher 
Searcher@3346cd5[implicitcoll_a_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C3)))}
   [junit4]   2> 2212399 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 7
   [junit4]   2> 2212399 INFO  (qtp2140930447-25226) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 2212399 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n1]  webapp=/solr 
path=/update 
params={_stateVer_=implicitcoll:5&commit=true&wt=javabin&version=2&_route_=a}{add=[6
 (1579746281359671296), 7 (1579746281359671297), 8 
(1579746281359671298)],commit=} 0 14
   [junit4]   2> 2212402 INFO  (qtp2099364128-25202) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/|http://127.0.0.1:57592/solr/implicitcoll_a_replica_n2/&rows=10&version=2&q=*:*&NOW=1506563454986&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 2212403 INFO  (qtp621682900-25236) [    ] o.a.s.c.S.Request 
[implicitcoll_b_replica_n6]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57594/solr/implicitcoll_b_replica_n6/|http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&rows=10&version=2&q=*:*&NOW=1506563454986&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 2212404 INFO  (qtp2140930447-25223) [    ] o.a.s.c.S.Request 
[implicitcoll_c_replica_n10]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/|http://127.0.0.1:57595/solr/implicitcoll_c_replica_n8/&rows=10&version=2&q=*:*&NOW=1506563454986&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 2212406 INFO  (qtp2099364128-25202) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&_stateVer_=implicitcoll:5&shards.purpose=64&NOW=1506563454986&ids=6,7,8&isShard=true&shard.url=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/|http://127.0.0.1:57592/solr/implicitcoll_a_replica_n2/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2212406 INFO  (qtp2099364128-25206) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=implicitcoll:5&wt=javabin&version=2} hits=3 status=0 
QTime=6
   [junit4]   2> 2212408 INFO  (qtp621682900-25236) [    ] o.a.s.c.S.Request 
[implicitcoll_b_replica_n6]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=implicitcoll:5&wt=javabin&version=2&_route_=b} hits=0 
status=0 QTime=0
   [junit4]   2> 2212409 INFO  (qtp2099364128-25202) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=implicitcoll:5&wt=javabin&version=2&_route_=a} hits=3 
status=0 QTime=0
   [junit4]   2> 2212416 INFO  (qtp621682900-25236) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1579746281385885696&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1579746281385885696)} 0 2
   [junit4]   2> 2212417 INFO  (qtp2099364128-25210) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@30ebeb3e[implicitcoll_a_replica_n1] 
realtime]
   [junit4]   2> 2212420 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n8]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1579746281390080000&distrib.from=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1579746281390080000)} 0 1
   [junit4]   2> 2212420 INFO  (qtp2140930447-25222) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6210c5cb[implicitcoll_a_replica_n2] 
realtime]
   [junit4]   2> 2212421 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1579746281390080000&distrib.from=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1579746281390080000)} 0 2
   [junit4]   2> 2212421 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1579746281390080000)} 0 7
   [junit4]   2> 2212422 INFO  (qtp2140930447-25223) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n10]  webapp=/solr 
path=/update 
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1579746281390080000)} 0 7
   [junit4]   2> 2212422 INFO  (qtp1224334062-25217) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n4]  webapp=/solr 
path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1579746281385885696)} 0 12
   [junit4]   2> 2212424 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281400565760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212424 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2556b33b 
commitCommandVersion:1579746281400565760
   [junit4]   2> 2212424 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281400565760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212424 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2d0e169d 
commitCommandVersion:1579746281400565760
   [junit4]   2> 2212424 INFO  (qtp621682900-25233) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281400565760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212424 INFO  (qtp1224334062-25219) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281400565760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212425 INFO  (qtp1224334062-25219) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212424 INFO  (qtp621682900-25233) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212425 INFO  (qtp1224334062-25219) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212426 INFO  (qtp2140930447-25225) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281402662912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212426 INFO  (qtp2140930447-25225) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212426 INFO  (qtp1224334062-25219) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2212426 INFO  (qtp2099364128-25207) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281402662912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212426 INFO  (qtp2099364128-25207) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212426 INFO  (qtp621682900-25233) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212427 INFO  (qtp2140930447-25225) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212427 INFO  (qtp621682900-25233) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n6]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 2
   [junit4]   2> 2212427 INFO  (qtp2140930447-25225) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n10]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 2212427 INFO  (qtp2099364128-25207) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212427 INFO  (qtp2099364128-25207) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n8]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2212427 INFO  (qtp2140930447-25211) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5dd26105[implicitcoll_a_replica_n2] 
main]
   [junit4]   2> 2212428 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212428 INFO  (qtp2099364128-25204) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7b4a0772[implicitcoll_a_replica_n1] 
main]
   [junit4]   2> 2212428 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212429 INFO  (searcherExecutor-11282-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n2] Registered new searcher 
Searcher@5dd26105[implicitcoll_a_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2212429 INFO  (qtp2140930447-25211) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 5
   [junit4]   2> 2212429 INFO  (searcherExecutor-11278-thread-1) [    ] 
o.a.s.c.SolrCore [implicitcoll_a_replica_n1] Registered new searcher 
Searcher@7b4a0772[implicitcoll_a_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2212430 INFO  (qtp2099364128-25204) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 2212430 INFO  (qtp1224334062-25218) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n4]  webapp=/solr 
path=/update 
params={_stateVer_=implicitcoll:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 2212432 INFO  (qtp2099364128-25206) [    ] o.a.s.c.S.Request 
[implicitcoll_a_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57595/solr/implicitcoll_a_replica_n1/|http://127.0.0.1:57592/solr/implicitcoll_a_replica_n2/&rows=10&version=2&q=*:*&NOW=1506563455017&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 2212432 INFO  (qtp2140930447-25227) [    ] o.a.s.c.S.Request 
[implicitcoll_c_replica_n10]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/|http://127.0.0.1:57595/solr/implicitcoll_c_replica_n8/&rows=10&version=2&q=*:*&NOW=1506563455017&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 2212433 INFO  (qtp1224334062-25215) [    ] o.a.s.c.S.Request 
[implicitcoll_b_replica_n4]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=implicitcoll:5&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=http://127.0.0.1:57591/solr/implicitcoll_b_replica_n4/|http://127.0.0.1:57594/solr/implicitcoll_b_replica_n6/&rows=10&version=2&q=*:*&NOW=1506563455017&isShard=true&wt=javabin}
 hits=0 status=0 QTime=0
   [junit4]   2> 2212433 INFO  (qtp2099364128-25205) [    ] o.a.s.c.S.Request 
[implicitcoll_c_replica_n8]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=implicitcoll:5&wt=javabin&version=2} hits=0 status=0 
QTime=2
   [junit4]   2> 2212437 INFO  (qtp2099364128-25206) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_c_replica_n8]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/&wt=javabin&version=2}{add=[9
 (1579746281412100096), 10 (1579746281413148672), 11 (1579746281413148673)]} 0 0
   [junit4]   2> 2212439 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281416294400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212439 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212439 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212439 INFO  (qtp2099364128-25208) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 2212440 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281417342976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212440 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212440 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212440 INFO  (qtp1224334062-25214) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281417342976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212440 INFO  (qtp1224334062-25214) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212440 INFO  (qtp1224334062-25214) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212441 INFO  (qtp2140930447-25222) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_a_replica_n2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 2212441 INFO  (qtp1224334062-25214) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [implicitcoll_b_replica_n4]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57592/solr/implicitcoll_c_replica_n10/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 2212441 INFO  (qtp621682900-25234) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281418391552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212441 INFO  (qtp621682900-25234) [    ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2212441 INFO  (qtp621682900-25234) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2212442 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579746281419440128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2212442 INFO  (qtp2099364128-25210) [    ] 
o.a.s.u.SolrIndexWriter

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

 on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2751130 INFO  
(SUITE-TestTlogReplica-seed#[9F0845C474AC9EED]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59259 59259
   [junit4]   2> 2751162 INFO  (Thread-3887) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:59259 59259
   [junit4]   2> 2751285 WARN  (Thread-3887) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        32      /solr/configs/conf
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        8       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        32      
/solr/collections/tlog_replica_test_create_delete/state.json
   [junit4]   2>        27      
/solr/collections/tlog_replica_test_add_remove_tlog_replica/state.json
   [junit4]   2>        26      
/solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json
   [junit4]   2>        26      
/solr/collections/tlog_replica_test_remove_leader/state.json
   [junit4]   2>        26      
/solr/collections/tlog_replica_test_kill_tlog_replica/state.json
   [junit4]   2>        26      
/solr/collections/tlog_replica_test_basic_leader_election/state.json
   [junit4]   2>        24      
/solr/collections/tlog_replica_test_recovery/state.json
   [junit4]   2>        23      
/solr/collections/tlog_replica_test_kill_leader/state.json
   [junit4]   2>        15      
/solr/collections/tlog_replica_test_real_time_get/state.json
   [junit4]   2>        15      
/solr/collections/tlog_replica_test_only_leader_indexes/state.json
   [junit4]   2>        15      
/solr/collections/tlog_replica_test_delete_by_id/state.json
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        6       
/solr/collections/tlog_replica_test_add_docs/state.json
   [junit4]   2>        3       
/solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/98734170998702100-core_node3-n_0000000000
   [junit4]   2>        3       
/solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/98734170998702091-core_node11-n_0000000000
   [junit4]   2>        2       
/solr/collections/tlog_replica_test_kill_tlog_replica/leader_elect/shard1/election/98734170998702085-core_node4-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/98734170998702100-127.0.0.1:59261_solr-n_0000000005
   [junit4]   2>        2       
/solr/collections/tlog_replica_test_real_time_get/leader_elect/shard1/election/98734170998702094-core_node5-n_0000000000
   [junit4]   2>        2       
/solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/98734170998702091-core_node9-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/collections
   [junit4]   2>        6       /solr/overseer/queue
   [junit4]   2>        6       /solr/overseer/collection-queue-work
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_9F0845C474AC9EED-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{foo=PostingsFormat(name=Memory), title_s=PostingsFormat(name=Memory), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
foo_s=PostingsFormat(name=Memory)}, 
docValues:{_version_=DocValuesFormat(name=Lucene70), 
id=DocValuesFormat(name=Asserting), 
inplace_updatable_int=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1827, 
maxMBSortInHeap=6.079158602549358, sim=RandomSimilarity(queryNorm=false): {}, 
locale=ha-NG, timezone=America/Swift_Current
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 
(64-bit)/cpus=3,threads=1,free=282778320,total=521666560
   [junit4]   2> NOTE: All tests run in this JVM: [TestRecovery, 
TestBlobHandler, TestQueryWrapperFilter, TestRangeQuery, RemoteQueryErrorTest, 
TestHalfAndHalfDocValues, DistributedSuggestComponentTest, MBeansHandlerTest, 
TestCharFilters, TestEmbeddedSolrServerAdminHandler, TestSolrDeletionPolicy1, 
TestTrieFacet, TestDistribDocBasedVersion, 
DocExpirationUpdateProcessorFactoryTest, TestStressLucene, 
TestUseDocValuesAsStored2, TestPointFields, TestDynamicFieldCollectionResource, 
BasicZkTest, TestClusterStateMutator, DocValuesNotIndexedTest, 
TestFastLRUCache, SolrPluginUtilsTest, SolrMetricManagerTest, 
ChaosMonkeySafeLeaderTest, TestRandomFlRTGCloud, ExitableDirectoryReaderTest, 
SuggesterTSTTest, NodeMutatorTest, ShufflingReplicaListTransformerTest, 
TestOrdValues, TestSSLRandomization, DistributedQueueTest, ResponseHeaderTest, 
SuggestComponentContextFilterQueryTest, TestDistributedMissingSort, 
SaslZkACLProviderTest, FieldMutatingUpdateProcessorTest, SystemInfoHandlerTest, 
TermVectorComponentDistributedTest, TestAuthenticationFramework, 
SpellCheckComponentTest, TestLegacyNumericRangeQueryBuilder, TestDistribIDF, 
TestPerFieldSimilarity, TestJoin, TestJsonFacets, CoreAdminRequestStatusTest, 
TriLevelCompositeIdRoutingTest, SolrGraphiteReporterTest, 
TestSchemaSimilarityResource, TestExportWriter, TestRealTimeGet, 
MigrateRouteKeyTest, SimpleCollectionCreateDeleteTest, 
ClassificationUpdateProcessorTest, PluginInfoTest, SolrMetricsIntegrationTest, 
TestWordDelimiterFilterFactory, ConcurrentDeleteAndCreateCollectionTest, 
SolrCloudReportersTest, TestSerializedLuceneMatchVersion, 
DistributedQueryComponentCustomSortTest, BooleanFieldTest, 
HLLSerializationTest, TestCloudRecovery, CdcrUpdateLogTest, 
DirectSolrSpellCheckerTest, HighlighterMaxOffsetTest, SolrCmdDistributorTest, 
TestLRUCache, StatsComponentTest, PreAnalyzedFieldTest, TestRTimerTree, 
TestSolrConfigHandler, TestLMJelinekMercerSimilarityFactory, 
SpellCheckCollatorWithCollapseTest, MoveReplicaHDFSTest, BadIndexSchemaTest, 
TestPolicyCloud, TestSolrConfigHandlerCloud, AnalyticsQueryTest, TestRTGBase, 
CdcrReplicationDistributedZkTest, TestExactStatsCache, 
SubstringBytesRefFilterTest, BJQParserTest, 
DistributedQueryElevationComponentTest, TestPartialUpdateDeduplication, 
TestSolrQueryParser, TestLegacyFieldCache, 
TestManagedSynonymGraphFilterFactory, DistributedFacetPivotSmallTest, 
CoreSorterTest, LargeFieldTest, SharedFSAutoReplicaFailoverTest, FileUtilsTest, 
TestEmbeddedSolrServerSchemaAPI, TestXmlQParserPlugin, TestDocumentBuilder, 
ZkStateWriterTest, LeaderFailoverAfterPartitionTest, ZkSolrClientTest, 
SignatureUpdateProcessorFactoryTest, TestTrackingShardHandlerFactory, 
SampleTest, HdfsChaosMonkeySafeLeaderTest, TestIntervalFaceting, 
StatelessScriptUpdateProcessorFactoryTest, TestExclusionRuleCollectionAccess, 
TestFastOutputStream, TestMultiValuedNumericRangeQuery, ConnectionManagerTest, 
QueryEqualityTest, TestZkChroot, TestSolrCloudWithSecureImpersonation, 
TestCollationField, TestRecoveryHdfs, HdfsNNFailoverTest, TestCSVLoader, 
PolyFieldTest, ShowFileRequestHandlerTest, DeleteStatusTest, TestConfigOverlay, 
OverriddenZkACLAndCredentialsProvidersTest, TestGraphMLResponseWriter, 
CloudMLTQParserTest, WrapperMergePolicyFactoryTest, TestSolrIndexConfig, 
TestReload, TestApiFramework, TestFastWriter, TestQueryUtils, 
AtomicUpdateProcessorFactoryTest, TestSlowCompositeReaderWrapper, 
OpenExchangeRatesOrgProviderTest, TestMaxTokenLenTokenizer, 
MoreLikeThisHandlerTest, TestPKIAuthenticationPlugin, TestCrossCoreJoin, 
TestSchemalessBufferedUpdates, UpdateRequestProcessorFactoryTest, 
SolrCoreCheckLockOnStartupTest, OverseerStatusTest, 
TestSimpleTrackingShardHandler, ExplicitHLLTest, MoveReplicaTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestCryptoKeys, 
TestDistributedStatsComponentCardinality, TestSearcherReuse, 
TestInfoStreamLogging, PeerSyncReplicationTest, TestRebalanceLeaders, 
SearchHandlerTest, TestScoreJoinQPScore, TestNoOpRegenerator, 
HttpPartitionTest, LoggingHandlerTest, PingRequestHandlerTest, TestNRTOpen, 
TestRawTransformer, TestAddFieldRealTimeGet, TestFieldCache, 
TestDynamicLoading, LeaderElectionIntegrationTest, 
CollectionsAPIDistributedZkTest, GraphQueryTest, TestSolrQueryResponse, 
TestCloudSchemaless, TestNumericTerms64, TestCorePropertiesReload, 
TestElisionMultitermQuery, TestHdfsBackupRestoreCore, InfoHandlerTest, 
UpdateParamsTest, TestNamedUpdateProcessors, BasicDistributedZkTest, 
TestRandomFaceting, AutoCommitTest, TestHashPartitioner, 
DistributedTermsComponentTest, SolrCoreTest, ConvertedLegacyTest, 
TestFiltering, BasicFunctionalityTest, TestBadConfig, TestIndexSearcher, 
HighlighterTest, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, 
SuggesterFSTTest, SolrRequestParserTest, DocValuesTest, 
IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, TestWriterPerf, 
PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, 
RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, 
PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, 
TestIndexingPerformance, FastVectorHighlighterTest, ReturnFieldsTest, 
TestAnalyzedSuggestions, JsonLoaderTest, TestComponentsName, SOLR749Test, 
TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, 
CopyFieldTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, 
TestPhraseSuggestions, TestSweetSpotSimilarityFactory, 
TestBM25SimilarityFactory, ScriptEngineTest, ChaosMonkeyNothingIsSafeTest, 
AssignTest, BaseCdcrDistributedZkTest, CdcrBootstrapTest, 
CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, 
DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, 
DistribJoinFromCollectionTest, LeaderElectionContextKeyTest, 
LeaderInitiatedRecoveryOnCommitTest, ReplaceNodeTest, SolrCloudExampleTest, 
TestCloudPseudoReturnFields, TestCloudSearcherWarming, 
TestDownShardTolerantSearch, TestHdfsCloudBackupRestore, 
TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, 
TestPrepRecovery, TestReplicaProperties, TestRequestForwarding, 
TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestTlogReplica]
   [junit4] Completed [562/732 (2!)] on J1 in 105.59s, 13 tests, 1 failure <<< 
FAILURES!

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

Reply via email to