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-6.x-MacOSX (64bit/jdk1.8.0) - Build # 784 - Still Unstable!
Date Sun, 26 Mar 2017 02:44:03 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/784/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

3 tests failed.
FAILED:  org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI

Error Message:
Error from server at https://127.0.0.1:60444/solr: Failed to create shard

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:60444/solr: Failed to create shard
	at __randomizedtesting.SeedInfo.seed([566E19D6211B09A7:3C8F97BD1C81BFDF]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:435)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:387)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1364)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1115)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1054)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177)
	at org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:103)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForHashRouter

Error Message:
Collection not found: routeFieldColl

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


FAILED:  org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter

Error Message:
Collection not found: withShardField

Stack Trace:
org.apache.solr.common.SolrException: Collection not found: withShardField
	at __randomizedtesting.SeedInfo.seed([566E19D6211B09A7:33EF1448DE2C657]:0)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1382)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1075)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1054)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
	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:745)




Build Log:
[...truncated 11998 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/init-core-data-001
   [junit4]   2> 1742868 WARN  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=33 numCloses=33
   [junit4]   2> 1742869 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1742873 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-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> 1742894 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001
   [junit4]   2> 1742894 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1742895 INFO  (Thread-2328) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1742895 INFO  (Thread-2328) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1742995 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:60435
   [junit4]   2> 1743110 INFO  (jetty-launcher-2184-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1743110 INFO  (jetty-launcher-2184-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1743110 INFO  (jetty-launcher-2184-thread-3) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1743110 INFO  (jetty-launcher-2184-thread-4) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1743112 INFO  (jetty-launcher-2184-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19aa2c1d{/solr,null,AVAILABLE}
   [junit4]   2> 1743114 INFO  (jetty-launcher-2184-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5335a1a0{SSL,[ssl, http/1.1]}{127.0.0.1:60440}
   [junit4]   2> 1743114 INFO  (jetty-launcher-2184-thread-2) [    ] o.e.j.s.Server Started @1748624ms
   [junit4]   2> 1743114 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60440}
   [junit4]   2> 1743114 ERROR (jetty-launcher-2184-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1743116 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.6.0
   [junit4]   2> 1743116 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1743116 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1743117 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-03-26T01:42:34.786Z
   [junit4]   2> 1743119 INFO  (jetty-launcher-2184-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e8e268{/solr,null,AVAILABLE}
   [junit4]   2> 1743120 INFO  (jetty-launcher-2184-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@659a8a89{SSL,[ssl, http/1.1]}{127.0.0.1:60441}
   [junit4]   2> 1743120 INFO  (jetty-launcher-2184-thread-4) [    ] o.e.j.s.Server Started @1748630ms
   [junit4]   2> 1743120 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60441}
   [junit4]   2> 1743121 ERROR (jetty-launcher-2184-thread-4) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1743121 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.6.0
   [junit4]   2> 1743121 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1743121 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1743121 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-03-26T01:42:34.791Z
   [junit4]   2> 1743144 INFO  (jetty-launcher-2184-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c11b7a8{/solr,null,AVAILABLE}
   [junit4]   2> 1743145 INFO  (jetty-launcher-2184-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24b9235d{/solr,null,AVAILABLE}
   [junit4]   2> 1743145 INFO  (jetty-launcher-2184-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1fc96c5c{SSL,[ssl, http/1.1]}{127.0.0.1:60444}
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-1) [    ] o.e.j.s.Server Started @1748656ms
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60444}
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@4d4d6a3e{SSL,[ssl, http/1.1]}{127.0.0.1:60445}
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-3) [    ] o.e.j.s.Server Started @1748656ms
   [junit4]   2> 1743146 ERROR (jetty-launcher-2184-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60445}
   [junit4]   2> 1743146 ERROR (jetty-launcher-2184-thread-3) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.6.0
   [junit4]   2> 1743146 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1743147 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1743147 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-03-26T01:42:34.817Z
   [junit4]   2> 1743147 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 6.6.0
   [junit4]   2> 1743155 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1743155 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1743155 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-03-26T01:42:34.825Z
   [junit4]   2> 1743148 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1743148 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1743172 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1743174 INFO  (jetty-launcher-2184-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60435/solr
   [junit4]   2> 1743174 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1743176 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1743178 INFO  (jetty-launcher-2184-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60435/solr
   [junit4]   2> 1743178 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1743193 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1743194 INFO  (jetty-launcher-2184-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60435/solr
   [junit4]   2> 1743195 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1743196 INFO  (jetty-launcher-2184-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60435/solr
   [junit4]   2> 1743239 INFO  (jetty-launcher-2184-thread-3) [n:127.0.0.1:60445_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1743239 INFO  (jetty-launcher-2184-thread-4) [n:127.0.0.1:60441_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1743240 INFO  (jetty-launcher-2184-thread-2) [n:127.0.0.1:60440_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1743240 INFO  (jetty-launcher-2184-thread-1) [n:127.0.0.1:60444_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1743241 INFO  (jetty-launcher-2184-thread-4) [n:127.0.0.1:60441_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60441_solr
   [junit4]   2> 1743242 INFO  (jetty-launcher-2184-thread-3) [n:127.0.0.1:60445_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60445_solr
   [junit4]   2> 1743242 INFO  (jetty-launcher-2184-thread-2) [n:127.0.0.1:60440_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60440_solr
   [junit4]   2> 1743243 INFO  (jetty-launcher-2184-thread-1) [n:127.0.0.1:60444_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60444_solr
   [junit4]   2> 1743243 INFO  (jetty-launcher-2184-thread-4) [n:127.0.0.1:60441_solr    ] o.a.s.c.Overseer Overseer (id=97680920056168456-127.0.0.1:60441_solr-n_0000000000) starting
   [junit4]   2> 1743246 INFO  (zkCallback-2197-thread-1-processing-n:127.0.0.1:60440_solr) [n:127.0.0.1:60440_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1743246 INFO  (zkCallback-2198-thread-1-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1743247 INFO  (zkCallback-2206-thread-1-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1743247 INFO  (zkCallback-2205-thread-1-processing-n:127.0.0.1:60445_solr) [n:127.0.0.1:60445_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1743273 INFO  (jetty-launcher-2184-thread-4) [n:127.0.0.1:60441_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60441_solr
   [junit4]   2> 1743276 INFO  (zkCallback-2206-thread-1-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743276 INFO  (zkCallback-2197-thread-1-processing-n:127.0.0.1:60440_solr) [n:127.0.0.1:60440_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743276 INFO  (zkCallback-2205-thread-1-processing-n:127.0.0.1:60445_solr) [n:127.0.0.1:60445_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743276 INFO  (zkCallback-2198-thread-1-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743387 INFO  (jetty-launcher-2184-thread-3) [n:127.0.0.1:60445_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/.
   [junit4]   2> 1743388 INFO  (jetty-launcher-2184-thread-1) [n:127.0.0.1:60444_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/.
   [junit4]   2> 1743407 INFO  (jetty-launcher-2184-thread-4) [n:127.0.0.1:60441_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/.
   [junit4]   2> 1743459 INFO  (jetty-launcher-2184-thread-2) [n:127.0.0.1:60440_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/.
   [junit4]   2> 1745060 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1745063 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60435/solr ready
   [junit4]   2> 1745136 INFO  (TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[566E19D6211B09A7]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCustomCollectionsAPI
   [junit4]   2> 1745138 INFO  (TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[566E19D6211B09A7]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1745171 INFO  (qtp678393414-11513) [n:127.0.0.1:60444_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&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1745174 INFO  (OverseerThreadFactory-4972-thread-1-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.CreateCollectionCmd Create collection implicitcoll
   [junit4]   2> 1745175 WARN  (OverseerThreadFactory-4972-thread-1-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 5 on collection implicitcoll is higher than or equal to 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> 1745313 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1745313 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1745314 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1745314 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1745371 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1745387 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1745398 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1745429 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1745440 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1745450 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1745448 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1745455 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1745457 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1745483 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1745490 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1745600 INFO  (zkCallback-2206-thread-1-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_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> 1745600 INFO  (zkCallback-2197-thread-1-processing-n:127.0.0.1:60440_solr) [n:127.0.0.1:60440_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> 1745600 INFO  (zkCallback-2205-thread-1-processing-n:127.0.0.1:60445_solr) [n:127.0.0.1:60445_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> 1745601 INFO  (zkCallback-2198-thread-1-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_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> 1746462 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746485 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746487 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746493 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746494 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746500 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.s.IndexSchema [implicitcoll_c_replica5] Schema name=cloud-dynamic
   [junit4]   2> 1746501 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746524 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.IndexSchema [implicitcoll_a_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1746528 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.s.IndexSchema [implicitcoll_b_replica5] Schema name=cloud-dynamic
   [junit4]   2> 1746537 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.s.IndexSchema [implicitcoll_a_replica5] Schema name=cloud-dynamic
   [junit4]   2> 1746540 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.IndexSchema [implicitcoll_c_replica4] Schema name=cloud-dynamic
   [junit4]   2> 1746543 WARN  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.IndexSchema [implicitcoll_a_replica1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746611 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746611 WARN  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.s.IndexSchema [implicitcoll_b_replica5] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746609 WARN  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.s.IndexSchema [implicitcoll_c_replica5] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746613 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746613 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746609 WARN  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.IndexSchema [implicitcoll_c_replica4] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746605 WARN  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.s.IndexSchema [implicitcoll_a_replica5] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746604 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746614 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746604 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746614 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica5' using configuration from collection implicitcoll
   [junit4]   2> 1746615 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746615 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.SolrCore [[implicitcoll_b_replica5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/implicitcoll_b_replica5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/./implicitcoll_b_replica5/data/]
   [junit4]   2> 1746584 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746583 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746581 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746575 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.IndexSchema [implicitcoll_c_replica3] Schema name=cloud-dynamic
   [junit4]   2> 1746573 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746570 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746570 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746568 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 1746655 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema [implicitcoll_c_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1746659 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.IndexSchema [implicitcoll_a_replica3] Schema name=cloud-dynamic
   [junit4]   2> 1746661 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.IndexSchema [implicitcoll_b_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1746623 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica5' using configuration from collection implicitcoll
   [junit4]   2> 1746680 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746680 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.SolrCore [[implicitcoll_c_replica5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/implicitcoll_c_replica5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/./implicitcoll_c_replica5/data/]
   [junit4]   2> 1746685 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.IndexSchema [implicitcoll_c_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1746687 WARN  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema [implicitcoll_c_replica2] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746687 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746692 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.IndexSchema [implicitcoll_b_replica3] Schema name=cloud-dynamic
   [junit4]   2> 1746695 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.IndexSchema [implicitcoll_b_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1746623 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica5' using configuration from collection implicitcoll
   [junit4]   2> 1746731 WARN  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.IndexSchema [implicitcoll_c_replica3] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746730 WARN  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.IndexSchema [implicitcoll_b_replica1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746756 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746727 WARN  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.IndexSchema [implicitcoll_b_replica3] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746757 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746757 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica3' using configuration from collection implicitcoll
   [junit4]   2> 1746757 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746757 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrCore [[implicitcoll_c_replica3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/implicitcoll_c_replica3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/./implicitcoll_c_replica3/data/]
   [junit4]   2> 1746757 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica3' using configuration from collection implicitcoll
   [junit4]   2> 1746758 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746758 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore [[implicitcoll_b_replica3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/implicitcoll_b_replica3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/./implicitcoll_b_replica3/data/]
   [junit4]   2> 1746759 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746759 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.SolrCore [[implicitcoll_a_replica5] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/implicitcoll_a_replica5], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/./implicitcoll_a_replica5/data/]
   [junit4]   2> 1746759 WARN  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.IndexSchema [implicitcoll_c_replica1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746721 WARN  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.IndexSchema [implicitcoll_a_replica3] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746717 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica2' using configuration from collection implicitcoll
   [junit4]   2> 1746692 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.IndexSchema [implicitcoll_a_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1746760 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746760 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore [[implicitcoll_c_replica2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/implicitcoll_c_replica2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/./implicitcoll_c_replica2/data/]
   [junit4]   2> 1746760 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746680 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746679 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica1' using configuration from collection implicitcoll
   [junit4]   2> 1746655 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema [implicitcoll_a_replica4] Schema name=cloud-dynamic
   [junit4]   2> 1746762 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746762 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrCore [[implicitcoll_a_replica1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/implicitcoll_a_replica1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/./implicitcoll_a_replica1/data/]
   [junit4]   2> 1746763 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica3' using configuration from collection implicitcoll
   [junit4]   2> 1746763 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746763 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore [[implicitcoll_a_replica3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/implicitcoll_a_replica3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/./implicitcoll_a_replica3/data/]
   [junit4]   2> 1746759 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746756 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746755 WARN  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.IndexSchema [implicitcoll_b_replica2] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746765 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746751 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.IndexSchema [implicitcoll_b_replica4] Schema name=cloud-dynamic
   [junit4]   2> 1746773 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica4' using configuration from collection implicitcoll
   [junit4]   2> 1746795 WARN  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.IndexSchema [implicitcoll_a_replica2] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746795 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746799 WARN  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema [implicitcoll_a_replica4] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746800 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746801 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica1' using configuration from collection implicitcoll
   [junit4]   2> 1746801 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746801 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrCore [[implicitcoll_b_replica1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/implicitcoll_b_replica1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/./implicitcoll_b_replica1/data/]
   [junit4]   2> 1746803 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica2' using configuration from collection implicitcoll
   [junit4]   2> 1746803 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746803 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore [[implicitcoll_b_replica2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/implicitcoll_b_replica2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/./implicitcoll_b_replica2/data/]
   [junit4]   2> 1746804 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica2' using configuration from collection implicitcoll
   [junit4]   2> 1746805 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746805 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore [[implicitcoll_a_replica2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/implicitcoll_a_replica2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/./implicitcoll_a_replica2/data/]
   [junit4]   2> 1746805 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_a_replica4' using configuration from collection implicitcoll
   [junit4]   2> 1746805 WARN  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.IndexSchema [implicitcoll_b_replica4] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 1746805 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746806 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore [[implicitcoll_a_replica4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/implicitcoll_a_replica4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node3/./implicitcoll_a_replica4/data/]
   [junit4]   2> 1746807 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1746807 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746807 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrCore [[implicitcoll_c_replica4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/implicitcoll_c_replica4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node2/./implicitcoll_c_replica4/data/]
   [junit4]   2> 1746807 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_b_replica4' using configuration from collection implicitcoll
   [junit4]   2> 1746808 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746808 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrCore [[implicitcoll_b_replica4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/implicitcoll_b_replica4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node1/./implicitcoll_b_replica4/data/]
   [junit4]   2> 1746808 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.CoreContainer Creating SolrCore 'implicitcoll_c_replica1' using configuration from collection implicitcoll
   [junit4]   2> 1746809 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1746809 INFO  (qtp1611764528-11486) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrCore [[implicitcoll_c_replica1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/implicitcoll_c_replica1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001/tempDir-001/node4/./implicitcoll_c_replica1/data/]
   [junit4]   2> 1747067 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747067 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747069 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747069 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747071 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747071 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747073 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747073 INFO  (qtp678393414-11511) [n:127.0.0.1:60444_solr c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747078 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747078 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747081 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747081 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747083 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747083 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747085 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747086 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747087 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747087 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747089 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747089 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747090 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747090 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747092 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747092 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747102 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747102 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747104 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747104 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747107 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.SolrIndexSearcher Opening [Searcher@5fd59c4c[implicitcoll_b_replica3] main]
   [junit4]   2> 1747114 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c39f81d[implicitcoll_b_replica5] main]
   [junit4]   2> 1747114 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7eb39c5d[implicitcoll_c_replica2] main]
   [junit4]   2> 1747125 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747128 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747128 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747128 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.s.SolrIndexSearcher Opening [Searcher@25ab9bb5[implicitcoll_a_replica5] main]
   [junit4]   2> 1747129 INFO  (searcherExecutor-4981-thread-1-processing-n:127.0.0.1:60440_solr x:implicitcoll_b_replica5 s:b c:implicitcoll) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.SolrCore [implicitcoll_b_replica5] Registered new searcher Searcher@7c39f81d[implicitcoll_b_replica5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747129 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725335220224
   [junit4]   2> 1747130 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747130 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747133 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747133 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747135 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747135 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747137 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747137 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747137 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747137 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747137 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747137 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747139 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747139 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747139 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747139 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747140 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747141 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747141 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747141 INFO  (searcherExecutor-4983-thread-1-processing-n:127.0.0.1:60444_solr x:implicitcoll_a_replica5 s:a c:implicitcoll) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.SolrCore [implicitcoll_a_replica5] Registered new searcher Searcher@25ab9bb5[implicitcoll_a_replica5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747141 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725347803136
   [junit4]   2> 1747148 INFO  (qtp344025789-11474) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard b: total=5 found=1 timeoutin=9998ms
   [junit4]   2> 1747150 INFO  (zkCallback-2198-thread-3-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_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> 1747150 INFO  (zkCallback-2206-thread-1-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_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> 1747150 INFO  (zkCallback-2197-thread-1-processing-n:127.0.0.1:60440_solr) [n:127.0.0.1:60440_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> 1747153 INFO  (qtp678393414-11514) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard a: total=5 found=1 timeoutin=9999ms
   [junit4]   2> 1747154 INFO  (zkCallback-2205-thread-1-processing-n:127.0.0.1:60445_solr) [n:127.0.0.1:60445_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> 1747155 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747155 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@4432cac2[implicitcoll_a_replica2] main]
   [junit4]   2> 1747156 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747156 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747157 INFO  (searcherExecutor-4986-thread-1-processing-n:127.0.0.1:60445_solr x:implicitcoll_c_replica2 s:c c:implicitcoll) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore [implicitcoll_c_replica2] Registered new searcher Searcher@7eb39c5d[implicitcoll_c_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747157 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725364580352
   [junit4]   2> 1747158 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747158 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@101ab790[implicitcoll_b_replica2] main]
   [junit4]   2> 1747159 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747159 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747159 INFO  (searcherExecutor-4992-thread-1-processing-n:127.0.0.1:60440_solr x:implicitcoll_a_replica2 s:a c:implicitcoll) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore [implicitcoll_a_replica2] Registered new searcher Searcher@4432cac2[implicitcoll_a_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747159 INFO  (qtp344025789-11478) [n:127.0.0.1:60440_solr c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725366677504
   [junit4]   2> 1747160 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747160 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e3531db[implicitcoll_a_replica3] main]
   [junit4]   2> 1747160 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.SolrIndexSearcher Opening [Searcher@e4efa7d[implicitcoll_a_replica4] main]
   [junit4]   2> 1747160 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747160 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747160 INFO  (searcherExecutor-4991-thread-1-processing-n:127.0.0.1:60441_solr x:implicitcoll_b_replica2 s:b c:implicitcoll) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore [implicitcoll_b_replica2] Registered new searcher Searcher@101ab790[implicitcoll_b_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747161 INFO  (qtp1611764528-11491) [n:127.0.0.1:60441_solr c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725368774656
   [junit4]   2> 1747162 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747163 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747163 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747163 INFO  (searcherExecutor-4988-thread-1-processing-n:127.0.0.1:60441_solr x:implicitcoll_a_replica3 s:a c:implicitcoll) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore [implicitcoll_a_replica3] Registered new searcher Searcher@6e3531db[implicitcoll_a_replica3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747164 INFO  (qtp1611764528-11488) [n:127.0.0.1:60441_solr c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725371920384
   [junit4]   2> 1747167 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747168 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747168 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747168 INFO  (qtp801974982-11499) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725376114688
   [junit4]   2> 1747168 INFO  (searcherExecutor-4993-thread-1-processing-n:127.0.0.1:60445_solr x:implicitcoll_a_replica4 s:a c:implicitcoll) [n:127.0.0.1:60445_solr c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore [implicitcoll_a_replica4] Registered new searcher Searcher@e4efa7d[implicitcoll_a_replica4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747173 INFO  (qtp801974982-11496) [n:127.0.0.1:60445_solr c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard c: total=5 found=1 timeoutin=9999ms
   [junit4]   2> 1747177 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a046332[implicitcoll_c_replica5] main]
   [junit4]   2> 1747184 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24a6359a[implicitcoll_b_replica1] main]
   [junit4]   2> 1747190 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747193 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747193 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747194 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747194 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747195 INFO  (searcherExecutor-4985-thread-1-processing-n:127.0.0.1:60445_solr x:implicitcoll_b_replica3 s:b c:implicitcoll) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore [implicitcoll_b_replica3] Registered new searcher Searcher@5fd59c4c[implicitcoll_b_replica3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747195 INFO  (qtp801974982-11494) [n:127.0.0.1:60445_solr c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725404426240
   [junit4]   2> 1747196 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747196 INFO  (qtp678393414-11512) [n:127.0.0.1:60444_solr c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747198 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747199 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1747199 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1747199 INFO  (searcherExecutor-4982-thread-1-processing-n:127.0.0.1:60441_solr x:implicitcoll_c_replica5 s:c c:implicitcoll) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.SolrCore [implicitcoll_c_replica5] Registered new searcher Searcher@1a046332[implicitcoll_c_replica5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1747199 INFO  (qtp1611764528-11490) [n:127.0.0.1:60441_solr c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1562894725408620544
   [junit4]   2> 1747204 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747205 INFO  (qtp678393414-11504) [n:127.0.0.1:60444_solr c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747205 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1747205 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1747208 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1747208 INFO  (qtp344025789-11480) [n:127.0.0.1:60440_solr c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1747209 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1747209 INFO  (qtp344025789-11477) [n:127.0.0.1:60440_solr c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.jso

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

2479 INFO  (zkCallback-2206-thread-4-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 1822482 INFO  (jetty-closer-2185-thread-2) [    ] o.a.s.c.Overseer Overseer (id=97680920056168456-127.0.0.1:60441_solr-n_0000000000) closing
   [junit4]   2> 1822483 INFO  (coreCloseExecutor-5096-thread-1) [n:127.0.0.1:60445_solr c:testCreateShardRepFactor s:a r:core_node1 x:testCreateShardRepFactor_a_replica1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.testCreateShardRepFactor.a.replica1
   [junit4]   2> 1822483 INFO  (coreCloseExecutor-5095-thread-1) [n:127.0.0.1:60444_solr c:testCreateShardRepFactor s:x r:core_node3 x:testCreateShardRepFactor_x_replica1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.testCreateShardRepFactor.x.replica1
   [junit4]   2> 1822484 INFO  (OverseerStateUpdate-97680920056168456-127.0.0.1:60441_solr-n_0000000000) [n:127.0.0.1:60441_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:60441_solr
   [junit4]   2> 1822485 WARN  (zkCallback-2205-thread-2-processing-n:127.0.0.1:60445_solr) [n:127.0.0.1:60445_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1822486 INFO  (jetty-closer-2185-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1822486 INFO  (jetty-closer-2185-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@24b9235d{/solr,null,UNAVAILABLE}
   [junit4]   2> 1822487 INFO  (zkCallback-2198-thread-6-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 1822488 INFO  (zkCallback-2206-thread-3-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 1822490 WARN  (zkCallback-2198-thread-6-processing-n:127.0.0.1:60441_solr) [n:127.0.0.1:60441_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1822491 INFO  (zkCallback-2206-thread-4-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1822491 INFO  (zkCallback-2206-thread-3-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60444_solr
   [junit4]   2> 1822492 INFO  (jetty-closer-2185-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1822492 INFO  (jetty-closer-2185-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e8e268{/solr,null,UNAVAILABLE}
   [junit4]   2> 1822496 WARN  (zkCallback-2206-thread-3-processing-n:127.0.0.1:60444_solr) [n:127.0.0.1:60444_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1822496 INFO  (jetty-closer-2185-thread-4) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1822496 INFO  (jetty-closer-2185-thread-4) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c11b7a8{/solr,null,UNAVAILABLE}
   [junit4]   2> 1822499 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60435 60435
   [junit4]   2> 1822502 INFO  (Thread-2328) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60435 60435
   [junit4]   2> 1827293 WARN  (Thread-2328) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	9	/solr/aliases.json
   [junit4]   2> 	7	/solr/configs/conf
   [junit4]   2> 	4	/solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	9	/solr/clusterprops.json
   [junit4]   2> 	9	/solr/clusterstate.json
   [junit4]   2> 	4	/solr/collections/testCreateShardRepFactor/state.json
   [junit4]   2> 	4	/solr/collections/routeFieldColl/state.json
   [junit4]   2> 	4	/solr/collections/implicitcoll/state.json
   [junit4]   2> 	4	/solr/collections/withShardField/state.json
   [junit4]   2> 	2	/solr/overseer_elect/election/97680920056168459-127.0.0.1:60445_solr-n_0000000001
   [junit4]   2> 	2	/solr/overseer_elect/election/97680920056168456-127.0.0.1:60441_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	63	/solr/overseer/collection-queue-work
   [junit4]   2> 	44	/solr/overseer/queue
   [junit4]   2> 	26	/solr/collections
   [junit4]   2> 	9	/solr/live_nodes
   [junit4]   2> 	8	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 1827294 INFO  (SUITE-CustomCollectionTest-seed#[566E19D6211B09A7]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_566E19D6211B09A7-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {_version_=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=1668, maxMBSortInHeap=6.605381881489262, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=sr, timezone=Asia/Beirut
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=107266712,total=492294144
   [junit4]   2> NOTE: All tests run in this JVM: [SampleTest, SystemInfoHandlerTest, TestSolrIndexConfig, CursorPagingTest, TestSolrQueryParserDefaultOperatorResource, TestJettySolrRunner, TestBM25SimilarityFactory, CollectionsAPIAsyncDistributedZkTest, TestFieldCacheSanityChecker, TestAddFieldRealTimeGet, ZkNodePropsTest, TestTolerantSearch, TestFieldCache, TestXmlQParser, XsltUpdateRequestHandlerTest, TestInfoStreamLogging, TestFilteredDocIdSet, TestRestManager, IndexSchemaRuntimeFieldTest, TestCloudInspectUtil, DistributedFacetPivotSmallTest, DeleteLastCustomShardedReplicaTest, CurrencyFieldXmlFileTest, TestStressVersions, ChaosMonkeyNothingIsSafeTest, DistributedFacetExistsSmallTest, JavabinLoaderTest, SolrCoreTest, TestConfigOverlay, ZkControllerTest, TestHashQParserPlugin, ShardRoutingTest, SpatialFilterTest, SolrPluginUtilsTest, TestHdfsCloudBackupRestore, DocumentAnalysisRequestHandlerTest, MetricUtilsTest, TestJsonFacetRefinement, TestSweetSpotSimilarityFactory, DirectSolrConnectionTest, TestNamedUpdateProcessors, CdcrRequestHandlerTest, TestExtendedDismaxParser, TestSSLRandomization, TestDistribIDF, JSONWriterTest, TestXIncludeConfig, HLLUtilTest, TestReloadDeadlock, InfixSuggestersTest, SolrCloudExampleTest, LeaderInitiatedRecoveryOnCommitTest, AnalysisErrorHandlingTest, TestUninvertingReader, TestBinaryResponseWriter, DocExpirationUpdateProcessorFactoryTest, NumericFieldsTest, HdfsSyncSliceTest, TestRandomCollapseQParserPlugin, CloudExitableDirectoryReaderTest, TestFastLRUCache, RankQueryTest, TestStressReorder, TestMiniSolrCloudClusterSSL, ExitableDirectoryReaderTest, SolrGangliaReporterTest, TestCustomSort, DistributedQueryElevationComponentTest, SimpleCollectionCreateDeleteTest, TestStressInPlaceUpdates, TestCharFilters, TestConfigSetsAPI, TestSolrCloudWithSecureImpersonation, TestRemoteStreaming, TestAuthenticationFramework, IndexBasedSpellCheckerTest, SimpleFacetsTest, DeleteStatusTest, TestFieldCacheSortRandom, TestCoreContainer, TestExportWriter, JsonValidatorTest, TestUnifiedSolrHighlighter, TimeZoneUtilsTest, TestLRUCache, TestSimpleQParserPlugin, DocValuesNotIndexedTest, TestGraphMLResponseWriter, DistributedSuggestComponentTest, TestUpdate, XmlUpdateRequestHandlerTest, DistributedMLTComponentTest, TestWordDelimiterFilterFactory, OverriddenZkACLAndCredentialsProvidersTest, ConnectionManagerTest, CdcrReplicationDistributedZkTest, AlternateDirectoryTest, TestTolerantUpdateProcessorCloud, DirectSolrSpellCheckerTest, MultiThreadedOCPTest, TestChildDocTransformer, TestSchemaVersionResource, TestAnalyzedSuggestions, SolrRequestParserTest, TestShortCircuitedRequests, TestSchemalessBufferedUpdates, TestHashPartitioner, AnalysisAfterCoreReloadTest, ParsingFieldUpdateProcessorsTest, HdfsTlogReplayBufferedWhileIndexingTest, SmileWriterTest, TestPKIAuthenticationPlugin, DisMaxRequestHandlerTest, TestIBSimilarityFactory, SimpleMLTQParserTest, TestCorePropertiesReload, BlockJoinFacetSimpleTest, SOLR749Test, SuggesterFSTTest, TestDynamicLoading, TestSolrDeletionPolicy1, TestRandomFlRTGCloud, TermsComponentTest, TestNRTOpen, DataDrivenBlockJoinTest, PathHierarchyTokenizerFactoryTest, TestSolrConfigHandlerConcurrent, UniqFieldsUpdateProcessorFactoryTest, TestRTGBase, PingRequestHandlerTest, TestDFISimilarityFactory, TestSQLHandlerNonCloud, TestDynamicFieldResource, TestFieldResource, JSONWriterTest, QueryElevationComponentTest, TestSortByMinMaxFunction, ReplicationFactorTest, BlobRepositoryCloudTest, FullHLLTest, V2ApiIntegrationTest, TestApiFramework, TestExactStatsCache, RemoteQueryErrorTest, DOMUtilTest, TestCollationFieldDocValues, TestConfigSetProperties, TestJmxIntegration, AddSchemaFieldsUpdateProcessorFactoryTest, TestUseDocValuesAsStored2, DateFieldTest, TestStressUserVersions, DistributedSpellCheckComponentTest, ZkCLITest, TestSurroundQueryParser, TestOverriddenPrefixQueryForCustomFieldType, DeleteReplicaTest, SuggestComponentContextFilterQueryTest, SolrTestCaseJ4Test, TestManagedSynonymFilterFactory, TestMiniSolrCloudCluster, CustomCollectionTest]
   [junit4] Completed [346/701 (1!)] on J0 in 84.44s, 4 tests, 3 errors <<< FAILURES!

[...truncated 44456 lines...]


Mime
View raw message