lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 56 - Still Unstable!
Date Thu, 20 Jul 2017 09:10:03 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/56/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

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([4D7F827C7CBD8FB2:182F6AEED0444042]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)


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

Error Message:
Could not find new collection splitByRouteKeyTest

Stack Trace:
java.lang.AssertionError: Could not find new collection splitByRouteKeyTest
	at __randomizedtesting.SeedInfo.seed([4D7F827C7CBD8FB2:C52BBDA6D241E24A]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkForCollection(AbstractFullDistribZkTestBase.java:1813)
	at org.apache.solr.cloud.ShardSplitTest.splitByRouteKeyTest(ShardSplitTest.java:763)
	at org.apache.solr.cloud.ShardSplitTest.test(ShardSplitTest.java:102)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12391 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/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/init-core-data-001
   [junit4]   2> 1602530 WARN  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=42 numCloses=42
   [junit4]   2> 1602530 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1602533 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 1602534 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-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/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001
   [junit4]   2> 1602534 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1602534 INFO  (Thread-3898) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1602534 INFO  (Thread-3898) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1602537 ERROR (Thread-3898) [    ] 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> 1602635 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:60327
   [junit4]   2> 1602690 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50000, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602698 INFO  (jetty-launcher-2587-thread-4) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1602698 INFO  (jetty-launcher-2587-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1602699 INFO  (jetty-launcher-2587-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1602698 INFO  (jetty-launcher-2587-thread-3) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1602701 INFO  (jetty-launcher-2587-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@174dd9fc{/solr,null,AVAILABLE}
   [junit4]   2> 1602701 INFO  (jetty-launcher-2587-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cdc6d40{/solr,null,AVAILABLE}
   [junit4]   2> 1602701 INFO  (jetty-launcher-2587-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@a33affa{SSL,[ssl, http/1.1]}{127.0.0.1:60329}
   [junit4]   2> 1602701 INFO  (jetty-launcher-2587-thread-2) [    ] o.e.j.s.Server Started @1606219ms
   [junit4]   2> 1602701 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60329}
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@793c9f27{SSL,[ssl, http/1.1]}{127.0.0.1:60330}
   [junit4]   2> 1602702 ERROR (jetty-launcher-2587-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-1) [    ] o.e.j.s.Server Started @1606219ms
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60330}
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1602702 ERROR (jetty-launcher-2587-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-07-20T08:28:28.907Z
   [junit4]   2> 1602702 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1602703 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1602703 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-07-20T08:28:28.908Z
   [junit4]   2> 1602704 INFO  (jetty-launcher-2587-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59a5c5f0{/solr,null,AVAILABLE}
   [junit4]   2> 1602705 INFO  (jetty-launcher-2587-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5b4c8860{SSL,[ssl, http/1.1]}{127.0.0.1:60331}
   [junit4]   2> 1602705 INFO  (jetty-launcher-2587-thread-4) [    ] o.e.j.s.Server Started @1606223ms
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60331}
   [junit4]   2> 1602706 ERROR (jetty-launcher-2587-thread-4) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47e0b675{/solr,null,AVAILABLE}
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@66d5a54d{SSL,[ssl, http/1.1]}{127.0.0.1:60334}
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-3) [    ] o.e.j.s.Server Started @1606224ms
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60334}
   [junit4]   2> 1602706 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1602707 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-07-20T08:28:28.912Z
   [junit4]   2> 1602707 ERROR (jetty-launcher-2587-thread-3) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1602707 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.1.0
   [junit4]   2> 1602707 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1602707 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 1602707 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-07-20T08:28:28.912Z
   [junit4]   2> 1602710 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1602710 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1602710 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1602711 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1602719 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602720 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50002, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602722 INFO  (jetty-launcher-2587-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60327/solr
   [junit4]   2> 1602723 INFO  (jetty-launcher-2587-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60327/solr
   [junit4]   2> 1602724 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50003, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602726 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50004, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602727 INFO  (jetty-launcher-2587-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60327/solr
   [junit4]   2> 1602728 INFO  (jetty-launcher-2587-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60327/solr
   [junit4]   2> 1602733 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50005, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602735 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d5f1ccac50006, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1602948 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1602949 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60329_solr
   [junit4]   2> 1602950 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.c.Overseer Overseer (id=98339344137650186-127.0.0.1:60329_solr-n_0000000000) starting
   [junit4]   2> 1602957 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1602957 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1602958 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1602959 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60329_solr
   [junit4]   2> 1602961 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60330_solr
   [junit4]   2> 1602961 INFO  (zkCallback-2607-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1602962 INFO  (zkCallback-2606-thread-1-processing-n:127.0.0.1:60330_solr) [n:127.0.0.1:60330_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1602962 INFO  (zkCallback-2609-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1602963 INFO  (zkCallback-2608-thread-1-processing-n:127.0.0.1:60331_solr) [n:127.0.0.1:60331_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1602964 INFO  (zkCallback-2607-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1602964 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60331_solr
   [junit4]   2> 1602965 INFO  (zkCallback-2608-thread-1-processing-n:127.0.0.1:60331_solr) [n:127.0.0.1:60331_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1602966 INFO  (zkCallback-2609-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1602967 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60334_solr
   [junit4]   2> 1602967 INFO  (zkCallback-2606-thread-1-processing-n:127.0.0.1:60330_solr) [n:127.0.0.1:60330_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1602969 INFO  (zkCallback-2607-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1602969 INFO  (zkCallback-2608-thread-1-processing-n:127.0.0.1:60331_solr) [n:127.0.0.1:60331_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1602978 INFO  (zkCallback-2609-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1602979 INFO  (zkCallback-2607-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1602980 INFO  (zkCallback-2609-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1602980 INFO  (zkCallback-2606-thread-1-processing-n:127.0.0.1:60330_solr) [n:127.0.0.1:60330_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 1602981 INFO  (zkCallback-2608-thread-1-processing-n:127.0.0.1:60331_solr) [n:127.0.0.1:60331_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1603066 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603070 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603074 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603075 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603075 INFO  (jetty-launcher-2587-thread-1) [n:127.0.0.1:60330_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/.
   [junit4]   2> 1603076 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603076 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603077 INFO  (jetty-launcher-2587-thread-3) [n:127.0.0.1:60334_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/.
   [junit4]   2> 1603096 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603097 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603101 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603102 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603102 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603102 INFO  (jetty-launcher-2587-thread-2) [n:127.0.0.1:60329_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/.
   [junit4]   2> 1603102 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1603103 INFO  (jetty-launcher-2587-thread-4) [n:127.0.0.1:60331_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/.
   [junit4]   2> 1603154 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1603156 INFO  (SUITE-CustomCollectionTest-seed#[4D7F827C7CBD8FB2]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60327/solr ready
   [junit4]   2> 1603178 INFO  (TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[4D7F827C7CBD8FB2]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCustomCollectionsAPI
   [junit4]   2> 1603179 INFO  (TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[4D7F827C7CBD8FB2]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1603195 INFO  (qtp697397274-16289) [n:127.0.0.1:60334_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b,c&replicationFactor=5&collection.configName=conf&maxShardsPerNode=6&name=implicitcoll&router.name=implicit&nrtReplicas=5&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1603197 INFO  (OverseerThreadFactory-8039-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.CreateCollectionCmd Create collection implicitcoll
   [junit4]   2> 1603198 WARN  (OverseerThreadFactory-8039-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] o.a.s.c.CreateCollectionCmd Specified number of replicas of 5 on collection implicitcoll is higher than the number of Solr instances currently live or live and part of your createNodeSet(4). It's unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 1603319 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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":"https://127.0.0.1:60329/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603329 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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":"https://127.0.0.1:60330/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603337 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60331/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603345 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60334/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603352 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60329/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603359 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60330/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603365 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60331/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603370 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60334/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603374 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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":"https://127.0.0.1:60329/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603378 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60330/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603383 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60331/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603387 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60334/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603391 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60329/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603396 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60330/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1603400 INFO  (OverseerStateUpdate-98339344137650186-127.0.0.1:60329_solr-n_0000000000) [n:127.0.0.1:60329_solr    ] 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_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:60331/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1604253 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n2&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604253 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1604266 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n3&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604293 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n4&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604319 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node14&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n4&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604319 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1604320 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&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> 1604321 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1604326 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node1&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> 1604326 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n3&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604327 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1604328 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n1&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604331 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n2&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604331 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n5&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604331 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica_n3&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604331 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica_n5&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604336 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n1&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604339 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&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> 1604339 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica_n5&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1604459 INFO  (zkCallback-2609-thread-1-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] 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> 1604459 INFO  (zkCallback-2608-thread-1-processing-n:127.0.0.1:60331_solr) [n:127.0.0.1:60331_solr    ] 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> 1604459 INFO  (zkCallback-2606-thread-1-processing-n:127.0.0.1:60330_solr) [n:127.0.0.1:60330_solr    ] 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> 1604459 INFO  (zkCallback-2607-thread-1-processing-n:127.0.0.1:60329_solr) [n:127.0.0.1:60329_solr    ] 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> 1604460 INFO  (zkCallback-2609-thread-2-processing-n:127.0.0.1:60334_solr) [n:127.0.0.1:60334_solr    ] 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> 1604460 INFO  (zkCallback-2606-thread-2-processing-n:127.0.0.1:60330_solr) [n:127.0.0.1:60330_solr    ] 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> 1605330 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605331 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605332 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605339 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.s.IndexSchema [implicitcoll_b_replica_n3] Schema name=cloud-dynamic
   [junit4]   2> 1605340 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.s.IndexSchema [implicitcoll_a_replica_n4] Schema name=cloud-dynamic
   [junit4]   2> 1605342 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.s.IndexSchema [implicitcoll_c_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 1605377 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605377 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n4' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605377 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.core.implicitcoll.a.replica_n4' (registry 'solr.core.implicitcoll.a.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605378 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605378 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.c.SolrCore [[implicitcoll_a_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/implicitcoll_a_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/./implicitcoll_a_replica_n4/data/]
   [junit4]   2> 1605378 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605378 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n2' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605378 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.core.implicitcoll.c.replica_n2' (registry 'solr.core.implicitcoll.c.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605379 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605379 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.c.SolrCore [[implicitcoll_c_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/implicitcoll_c_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/./implicitcoll_c_replica_n2/data/]
   [junit4]   2> 1605382 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605382 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n3' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605383 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60334.solr.core.implicitcoll.b.replica_n3' (registry 'solr.core.implicitcoll.b.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605383 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605383 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.c.SolrCore [[implicitcoll_b_replica_n3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/implicitcoll_b_replica_n3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node3/./implicitcoll_b_replica_n3/data/]
   [junit4]   2> 1605387 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605400 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.s.IndexSchema [implicitcoll_c_replica_n4] Schema name=cloud-dynamic
   [junit4]   2> 1605405 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605416 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605416 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n4' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605416 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.core.implicitcoll.c.replica_n4' (registry 'solr.core.implicitcoll.c.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605416 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605416 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.SolrCore [[implicitcoll_c_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/implicitcoll_c_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/./implicitcoll_c_replica_n4/data/]
   [junit4]   2> 1605424 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.s.IndexSchema [implicitcoll_a_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1605448 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605449 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605586 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.s.IndexSchema [implicitcoll_c_replica_n3] Schema name=cloud-dynamic
   [junit4]   2> 1605586 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.s.IndexSchema [implicitcoll_a_replica_n5] Schema name=cloud-dynamic
   [junit4]   2> 1605599 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605604 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605608 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605620 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605622 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605627 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605627 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605631 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1605661 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.s.IndexSchema [implicitcoll_a_replica_n3] Schema name=cloud-dynamic
   [junit4]   2> 1605661 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.s.IndexSchema [implicitcoll_a_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 1605661 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.s.IndexSchema [implicitcoll_c_replica_n5] Schema name=cloud-dynamic
   [junit4]   2> 1605663 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.s.IndexSchema [implicitcoll_b_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1605666 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.s.IndexSchema [implicitcoll_c_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1605667 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.s.IndexSchema [implicitcoll_b_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 1605667 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.s.IndexSchema [implicitcoll_b_replica_n4] Schema name=cloud-dynamic
   [junit4]   2> 1605667 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.s.IndexSchema [implicitcoll_b_replica_n5] Schema name=cloud-dynamic
   [junit4]   2> 1605670 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605670 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n1' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605673 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.core.implicitcoll.a.replica_n1' (registry 'solr.core.implicitcoll.a.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605752 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605752 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] 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/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/implicitcoll_a_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/./implicitcoll_a_replica_n1/data/]
   [junit4]   2> 1605759 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605760 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n5' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605805 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605805 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n3' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605805 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.core.implicitcoll.c.replica_n3' (registry 'solr.core.implicitcoll.c.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605805 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605805 INFO  (qtp503057075-16264) [n:127.0.0.1:60329_solr c:implicitcoll s:c r:core_node13 x:implicitcoll_c_replica_n3] o.a.s.c.SolrCore [[implicitcoll_c_replica_n3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/implicitcoll_c_replica_n3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/./implicitcoll_c_replica_n3/data/]
   [junit4]   2> 1605810 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605810 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n5' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605810 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.core.implicitcoll.b.replica_n5' (registry 'solr.core.implicitcoll.b.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605810 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605810 INFO  (qtp61945010-16278) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node10 x:implicitcoll_b_replica_n5] o.a.s.c.SolrCore [[implicitcoll_b_replica_n5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/implicitcoll_b_replica_n5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/./implicitcoll_b_replica_n5/data/]
   [junit4]   2> 1605810 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605810 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n1' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605811 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.core.implicitcoll.b.replica_n1' (registry 'solr.core.implicitcoll.b.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605811 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605811 INFO  (qtp61945010-16274) [n:127.0.0.1:60330_solr c:implicitcoll s:b r:core_node6 x:implicitcoll_b_replica_n1] o.a.s.c.SolrCore [[implicitcoll_b_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/implicitcoll_b_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/./implicitcoll_b_replica_n1/data/]
   [junit4]   2> 1605812 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605812 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n5' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605812 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.core.implicitcoll.c.replica_n5' (registry 'solr.core.implicitcoll.c.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605812 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605812 INFO  (qtp1952566812-16275) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node15 x:implicitcoll_c_replica_n5] o.a.s.c.SolrCore [[implicitcoll_c_replica_n5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/implicitcoll_c_replica_n5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/./implicitcoll_c_replica_n5/data/]
   [junit4]   2> 1605813 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605813 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n4' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605813 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.core.implicitcoll.b.replica_n4' (registry 'solr.core.implicitcoll.b.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605813 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605813 INFO  (qtp503057075-16257) [n:127.0.0.1:60329_solr c:implicitcoll s:b r:core_node9 x:implicitcoll_b_replica_n4] 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/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/implicitcoll_b_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/./implicitcoll_b_replica_n4/data/]
   [junit4]   2> 1605825 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605825 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n2' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605825 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60330.solr.core.implicitcoll.a.replica_n2' (registry 'solr.core.implicitcoll.a.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605825 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605825 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] 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/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/implicitcoll_a_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node1/./implicitcoll_a_replica_n2/data/]
   [junit4]   2> 1605826 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605826 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica_n3' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605826 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.core.implicitcoll.a.replica_n3' (registry 'solr.core.implicitcoll.a.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605826 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605826 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.c.SolrCore [[implicitcoll_a_replica_n3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/implicitcoll_a_replica_n3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/./implicitcoll_a_replica_n3/data/]
   [junit4]   2> 1605886 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60329.solr.core.implicitcoll.a.replica_n5' (registry 'solr.core.implicitcoll.a.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605887 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605887 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.c.SolrCore [[implicitcoll_a_replica_n5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/implicitcoll_a_replica_n5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node2/./implicitcoll_a_replica_n5/data/]
   [junit4]   2> 1605887 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605887 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica_n2' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605887 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.core.implicitcoll.b.replica_n2' (registry 'solr.core.implicitcoll.b.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605887 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605887 INFO  (qtp1952566812-16281) [n:127.0.0.1:60331_solr c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica_n2] o.a.s.c.SolrCore [[implicitcoll_b_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/implicitcoll_b_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/./implicitcoll_b_replica_n2/data/]
   [junit4]   2> 1605893 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1605893 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica_n1' using configuration from collection implicitcoll, trusted=true
   [junit4]   2> 1605894 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60331.solr.core.implicitcoll.c.replica_n1' (registry 'solr.core.implicitcoll.c.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b96f5c8
   [junit4]   2> 1605894 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1605894 INFO  (qtp1952566812-16282) [n:127.0.0.1:60331_solr c:implicitcoll s:c r:core_node11 x:implicitcoll_c_replica_n1] o.a.s.c.SolrCore [[implicitcoll_c_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/implicitcoll_c_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_4D7F827C7CBD8FB2-001/tempDir-001/node4/./implicitcoll_c_replica_n1/data/]
   [junit4]   2> 1606001 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606001 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606003 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606003 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606066 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606066 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606068 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606068 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606069 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606069 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606070 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606071 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606086 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606086 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606106 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@bf2d0eb[implicitcoll_b_replica_n3] main]
   [junit4]   2> 1606106 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606106 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606108 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606108 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606109 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606109 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606112 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@8a2646d[implicitcoll_c_replica_n4] main]
   [junit4]   2> 1606114 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606114 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606116 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606116 INFO  (qtp1952566812-16280) [n:127.0.0.1:60331_solr c:implicitcoll s:a r:core_node3 x:implicitcoll_a_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606117 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@4733c964[implicitcoll_c_replica_n2] main]
   [junit4]   2> 1606126 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1606126 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1606128 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1606129 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1606129 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1606129 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573429509969936384
   [junit4]   2> 1606129 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1606130 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606130 INFO  (qtp503057075-16262) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node5 x:implicitcoll_a_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606131 INFO  (qtp697397274-16288) [n:127.0.0.1:60334_solr c:implicitcoll s:c r:core_node12 x:implicitcoll_c_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1606135 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1606135 INFO  (qtp697397274-16290) [n:127.0.0.1:60334_solr c:implicitcoll s:b r:core_node8 x:implicitcoll_b_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573429509976227840
   [junit4]   2> 1606136 INFO  (searcherExecutor-8051-thread-1-processing-n:127.0.0.1:60330_solr x:implicitcoll_c_replica_n4 s:c c:implicitcoll r:core_node14) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.SolrCore [implicitcoll_c_replica_n4] Registered new searcher Searcher@8a2646d[implicitcoll_c_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1606138 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1606138 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1606143 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1606143 INFO  (qtp61945010-16277) [n:127.0.0.1:60330_solr c:implicitcoll s:a r:core_node2 x:implicitcoll_a_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1606143 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ae62165[implicitcoll_a_replica_n1] main]
   [junit4]   2> 1606145 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1606145 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1606145 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b32084f[implicitcoll_a_replica_n4] main]
   [junit4]   2> 1606146 INFO  (qtp61945010-16276) [n:127.0.0.1:60330_solr c:implicitcoll s:c r:core_node14 x:implicitcoll_c_replica_n4] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard c: total=5 found=1 timeoutin=9998ms
   [junit4]   2> 1606146 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1606146 INFO  (qtp503057075-16259) [n:127.0.0.1:60329_solr c:implicitcoll s:a r:core_node1 x:implicitcoll_a_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1573429509987762176
   [junit4]   2> 1606147 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1606147 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1606148 INFO  (qtp697397274-16285) [n:127.0.0.1:60334_solr c:implicitcoll s:a r:core_node4 x:implicitcoll_a_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1606148 INFO  (qtp

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

egistry=solr.collection.collection1.shard1.leader, tag=1353767532
   [junit4]   2> 2247232 INFO  (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] o.a.s.c.Overseer Overseer (id=98339383281385490-127.0.0.1:62549_r%2Fl-n_0000000004) closing
   [junit4]   2> 2247232 INFO  (OverseerStateUpdate-98339383281385490-127.0.0.1:62549_r%2Fl-n_0000000004) [n:127.0.0.1:62549_r%2Fl    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62549_r%2Fl
   [junit4]   2> 2247233 WARN  (zkCallback-3105-thread-2-processing-n:127.0.0.1:62549_r%2Fl) [n:127.0.0.1:62549_r%2Fl    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2247234 INFO  (zkCallback-3081-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 2247234 INFO  (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@3d805a06{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2247234 INFO  (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@677d1b53{/r/l,null,UNAVAILABLE}
   [junit4]   2> 2247237 INFO  (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] o.a.s.c.ChaosMonkey monkey: stop jetty! 62513
   [junit4]   2> 2247237 ERROR (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] 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> 2247239 INFO  (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[4D7F827C7CBD8FB2]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62510 62510
   [junit4]   2> 2247263 INFO  (Thread-3555) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62510 62510
   [junit4]   2> 2247489 WARN  (Thread-3555) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/aliases.json
   [junit4]   2> 	6	/solr/security.json
   [junit4]   2> 	6	/solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/clusterprops.json
   [junit4]   2> 	8	/solr/clusterstate.json
   [junit4]   2> 	6	/solr/collections/collection1/state.json
   [junit4]   2> 	2	/solr/overseer_elect/election/98339383281385481-127.0.0.1:62524_r%2Fl-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/collections
   [junit4]   2> 	7	/solr/live_nodes
   [junit4]   2> 	3	/solr/overseer/collection-queue-work
   [junit4]   2> 	2	/solr/overseer/queue
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_4D7F827C7CBD8FB2-001
   [junit4]   2> Jul 20, 2017 8:39:13 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {shard_s=Lucene50(blocksize=128), rnd_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_t=Lucene50(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{shard_s=DocValuesFormat(name=Direct), rnd_b=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Direct), n_ti=DocValuesFormat(name=Direct), a_t=DocValuesFormat(name=Direct), a_i=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=330, maxMBSortInHeap=6.499890630525896, sim=RandomSimilarity(queryNorm=false): {}, locale=de, timezone=Asia/Sakhalin
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=86271032,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [V2StandaloneTest, PreAnalyzedFieldManagedSchemaCloudTest, TestNRTOpen, TolerantUpdateProcessorTest, TestObjectReleaseTracker, FastVectorHighlighterTest, TestCustomDocTransformer, DistributedDebugComponentTest, SuggestComponentContextFilterQueryTest, TestDownShardTolerantSearch, TestJsonFacets, BasicDistributedZkTest, PrimitiveFieldTypeTest, OverseerRolesTest, TestQuerySenderNoQuery, CoreAdminRequestStatusTest, HttpSolrCallGetCoreTest, TestIBSimilarityFactory, TestFieldTypeResource, TestRawResponseWriter, SuggesterTest, FieldAnalysisRequestHandlerTest, AtomicUpdatesTest, TestRandomRequestDistribution, TestLocalFSCloudBackupRestore, RequestLoggingTest, TestCopyFieldCollectionResource, QueryResultKeyTest, ChaosMonkeyNothingIsSafeTest, CSVRequestHandlerTest, TestSolrCoreProperties, SolrGraphiteReporterTest, SolrCoreTest, TestSubQueryTransformer, TestConfigSetProperties, TestApiFramework, TestMiniSolrCloudClusterSSL, TestQuerySenderListener, DistributedFacetPivotSmallTest, CollectionsAPISolrJTest, BigEndianAscendingWordDeserializerTest, RecoveryAfterSoftCommitTest, TestFieldCacheSortRandom, AlternateDirectoryTest, MultiTermTest, CleanupOldIndexTest, UnloadDistributedZkTest, TestSolrCLIRunExample, TestScoreJoinQPScore, TestDistributedSearch, NoCacheHeaderTest, TestUniqueKeyFieldResource, CircularListTest, DistributedSpellCheckComponentTest, TestTrieFacet, TestShardHandlerFactory, TestAtomicUpdateErrorCases, AnalyticsQueryTest, ZkCLITest, UUIDFieldTest, TestPhraseSuggestions, SuggesterWFSTTest, TestFuzzyAnalyzedSuggestions, TestLegacyField, TestExtendedDismaxParser, BJQParserTest, TestSystemCollAutoCreate, TestAnalyzeInfixSuggestions, TestXmlQParserPlugin, TestIndexingPerformance, DateMathParserTest, TestMiniSolrCloudCluster, BitVectorTest, OutputWriterTest, TestNoOpRegenerator, HdfsWriteToMultipleCollectionsTest, RuleEngineTest, CollectionReloadTest, TestSchemaVersionResource, TestCustomSort, URLClassifyProcessorTest, LeaderFailureAfterFreshStartTest, ShardRoutingTest, TestNonDefinedSimilarityFactory, TestSolrQueryResponse, CollectionTooManyReplicasTest, TestStressCloudBlindAtomicUpdates, TestManagedSynonymFilterFactory, SliceStateTest, TestConfigSetsAPI, TestWriterPerf, SSLMigrationTest, PKIAuthenticationIntegrationTest, TestHighlightDedupGrouping, CloneFieldUpdateProcessorFactoryTest, TestRequestStatusCollectionAPI, DistanceUnitsTest, TestCloudDeleteByQuery, TestConfigOverlay, TestCursorMarkWithoutUniqueKey, RegexBoostProcessorTest, TestNestedDocsSort, ConnectionReuseTest, TestHashQParserPlugin, SpatialRPTFieldTypeTest, HighlighterTest, TestUpdate, DataDrivenBlockJoinTest, ZkStateWriterTest, TestMissingGroups, AnalysisErrorHandlingTest, TestInfoStreamLogging, RecoveryZkTest, ClusterStateTest, CursorPagingTest, TestStreamBody, LargeFieldTest, HdfsDirectoryTest, TestStressReorder, TestLMDirichletSimilarityFactory, WordBreakSolrSpellCheckerTest, QueryElevationComponentTest, SharedFSAutoReplicaFailoverUtilsTest, TestTolerantSearch, DistribJoinFromCollectionTest, HdfsRecoveryZkTest, TestPseudoReturnFields, TestHalfAndHalfDocValues, TestPointFields, ScriptEngineTest, DirectSolrConnectionTest, TestUseDocValuesAsStored2, TestCloudManagedSchema, TestReqParamsAPI, TestMacros, TestManagedResourceStorage, SortByFunctionTest, RequestHandlersTest, LeaderInitiatedRecoveryOnShardRestartTest, LeaderElectionContextKeyTest, TestMultiValuedNumericRangeQuery, SolrInfoBeanTest, BadCopyFieldTest, CoreAdminHandlerTest, PrimUtilsTest, HdfsNNFailoverTest, BlockDirectoryTest, TestSolrCloudWithKerberosAlt, HighlighterConfigTest, TestDistributedStatsComponentCardinality, TestJmxIntegration, DistribCursorPagingTest, TestFieldCacheWithThreads, RequiredFieldsTest, SimpleFacetsTest, TestImpersonationWithHadoopAuth, PeerSyncTest, DocValuesTest, DateRangeFieldTest, TestSolrDeletionPolicy2, TestNumericRangeQuery64, XsltUpdateRequestHandlerTest, ShufflingReplicaListTransformerTest, TestGeoJSONResponseWriter, TestOrdValues, TestRandomDVFaceting, GraphQueryTest, TestConfigsApi, TestLegacyNumericRangeQueryBuilder, ClusterStateUpdateTest, SpatialFilterTest, DirectUpdateHandlerOptimizeTest, TestQueryWrapperFilter, TestSweetSpotSimilarityFactory, CdcrVersionReplicationTest, MoveReplicaHDFSUlogDirTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, SyncSliceTest, BasicZkTest, LeaderElectionTest, ZkSolrClientTest, TestDistributedGrouping, TestFaceting, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, TestJoin, TestStressVersions, HardAutoCommitTest, TestRangeQuery, TestCoreContainer, TestGroupingSearch, TestSort, TestLazyCores, SoftAutoCommitTest, TestLFUCache, TestMergePolicyConfig, TestDocSet, TestBinaryField, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestDFRSimilarityFactory, TestPerFieldSimilarity, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, DateFieldTest, RAMDirectoryFactoryTest, TestUtils, ZkNodePropsTest, DistributedIntervalFacetingTest, AssignTest, ChaosMonkeySafeLeaderWithPullReplicasTest, ConcurrentDeleteAndCreateCollectionTest, ConfigSetsAPITest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, DeleteShardTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, MissingSegmentRecoveryTest, MoveReplicaTest, OverseerModifyCollectionTest, ReplaceNodeTest, ShardSplitTest]
   [junit4] Completed [540/728 (2!)] on J0 in 353.91s, 5 tests, 1 failure <<< FAILURES!

[...truncated 43980 lines...]

Mime
View raw message