Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/722/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC

3 tests failed.
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([902370B23B208B01:3815EE6FA441605B]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1379)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1072)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1042)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:232)
        at 
org.apache.solr.cloud.CustomCollectionTest.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.testCustomCollectionsAPI

Error Message:
Could not find collection : implicitcoll

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : implicitcoll
        at 
__randomizedtesting.SeedInfo.seed([902370B23B208B01:FAC2FED906BA3D79]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
        at 
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:245)
        at 
org.apache.solr.cloud.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.java:68)
        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.MultiThreadedOCPTest.test

Error Message:
Task 3001 did not complete, final state: FAILED expected same:<COMPLETED> was 
not:<FAILED>

Stack Trace:
java.lang.AssertionError: Task 3001 did not complete, final state: FAILED 
expected same:<COMPLETED> was not:<FAILED>
        at 
__randomizedtesting.SeedInfo.seed([902370B23B208B01:18774F6895DCE6F9]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotSame(Assert.java:641)
        at org.junit.Assert.assertSame(Assert.java:580)
        at 
org.apache.solr.cloud.MultiThreadedOCPTest.testDeduplicationOfSubmittedTasks(MultiThreadedOCPTest.java:250)
        at 
org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:64)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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 11292 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/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/init-core-data-001
   [junit4]   2> 563314 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 563317 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 563318 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-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/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001
   [junit4]   2> 563318 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 563319 INFO  (Thread-769) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 563319 INFO  (Thread-769) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 563428 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:62879
   [junit4]   2> 563445 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 563445 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 563445 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 563446 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 563449 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@66bad031{/solr,null,AVAILABLE}
   [junit4]   2> 563450 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b4e10fd{/solr,null,AVAILABLE}
   [junit4]   2> 563450 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4312a0e9{HTTP/1.1,[http/1.1]}{127.0.0.1:62881}
   [junit4]   2> 563450 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.e.j.s.Server Started @568851ms
   [junit4]   2> 563450 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62881}
   [junit4]   2> 563450 ERROR (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@37a66e56{HTTP/1.1,[http/1.1]}{127.0.0.1:62882}
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.e.j.s.Server Started @568853ms
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62882}
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 563452 ERROR (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 563452 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-26T14:08:29.633Z
   [junit4]   2> 563453 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 563453 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 563453 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 563453 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-26T14:08:29.634Z
   [junit4]   2> 563453 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1d52eb7c{/solr,null,AVAILABLE}
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@11a35365{/solr,null,AVAILABLE}
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@211a295c{HTTP/1.1,[http/1.1]}{127.0.0.1:62883}
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.e.j.s.Server Started @568855ms
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62883}
   [junit4]   2> 563454 ERROR (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 563454 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-26T14:08:29.635Z
   [junit4]   2> 563456 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d009685{HTTP/1.1,[http/1.1]}{127.0.0.1:62884}
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.e.j.s.Server Started @568858ms
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62884}
   [junit4]   2> 563457 ERROR (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.5.0
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-26T14:08:29.638Z
   [junit4]   2> 563457 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 563460 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 563462 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 563465 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 563468 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 563469 INFO  (jetty-launcher-1706-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62879/solr
   [junit4]   2> 563471 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 563473 INFO  (jetty-launcher-1706-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62879/solr
   [junit4]   2> 563473 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 563475 INFO  (jetty-launcher-1706-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62879/solr
   [junit4]   2> 563476 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 563479 INFO  (jetty-launcher-1706-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62879/solr
   [junit4]   2> 563546 INFO  (jetty-launcher-1706-thread-4) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 563547 INFO  (jetty-launcher-1706-thread-2) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 563547 INFO  (jetty-launcher-1706-thread-3) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 563547 INFO  (jetty-launcher-1706-thread-1) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 563549 INFO  (jetty-launcher-1706-thread-4) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:62882_solr
   [junit4]   2> 563551 INFO  (jetty-launcher-1706-thread-2) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62881_solr
   [junit4]   2> 563551 INFO  (jetty-launcher-1706-thread-3) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62884_solr
   [junit4]   2> 563551 INFO  (jetty-launcher-1706-thread-1) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62883_solr
   [junit4]   2> 563552 INFO  (jetty-launcher-1706-thread-4) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.Overseer Overseer 
(id=97525308424323081-127.0.0.1:62882_solr-n_0000000000) starting
   [junit4]   2> 563555 INFO  
(zkCallback-1728-thread-1-processing-n:127.0.0.1:62884_solr) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 563555 INFO  
(zkCallback-1727-thread-1-processing-n:127.0.0.1:62881_solr) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 563557 INFO  
(zkCallback-1725-thread-1-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 563557 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 563588 INFO  (jetty-launcher-1706-thread-4) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62882_solr
   [junit4]   2> 563590 INFO  
(zkCallback-1727-thread-1-processing-n:127.0.0.1:62881_solr) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 563590 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 563590 INFO  
(zkCallback-1728-thread-1-processing-n:127.0.0.1:62884_solr) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 563590 INFO  
(zkCallback-1725-thread-1-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 563661 INFO  (jetty-launcher-1706-thread-4) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/.
   [junit4]   2> 563662 INFO  (jetty-launcher-1706-thread-3) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node3/.
   [junit4]   2> 563662 INFO  (jetty-launcher-1706-thread-1) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/.
   [junit4]   2> 563665 INFO  (jetty-launcher-1706-thread-2) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/.
   [junit4]   2> 564217 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 564219 INFO  
(SUITE-CustomCollectionTest-seed#[902370B23B208B01]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62879/solr ready
   [junit4]   2> 564256 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[902370B23B208B01]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShardRepFactor
   [junit4]   2> 564260 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[902370B23B208B01]) [  
  ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 564276 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 564283 INFO  
(OverseerThreadFactory-2300-thread-1-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
testCreateShardRepFactor
   [junit4]   2> 564428 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2
   [junit4]   2> 564430 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2
   [junit4]   2> 564550 INFO  
(zkCallback-1725-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 564550 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 565452 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 565452 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 565463 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_b_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 565463 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_a_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 565497 WARN  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_b_replica1] default search field 
in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 565497 WARN  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_a_replica1] default search field 
in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 565499 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 565500 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 565502 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_b_replica1' 
using configuration from collection testCreateShardRepFactor
   [junit4]   2> 565502 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_a_replica1' 
using configuration from collection testCreateShardRepFactor
   [junit4]   2> 565502 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_a_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/testCreateShardRepFactor_a_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/./testCreateShardRepFactor_a_replica1/data/]
   [junit4]   2> 565502 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_b_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/testCreateShardRepFactor_b_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/./testCreateShardRepFactor_b_replica1/data/]
   [junit4]   2> 565591 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 565591 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 565591 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 565591 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 565593 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 565593 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 565593 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 565593 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 565593 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6603a795[testCreateShardRepFactor_b_replica1] main]
   [junit4]   2> 565593 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5c125b14[testCreateShardRepFactor_a_replica1] main]
   [junit4]   2> 565595 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 565595 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 565596 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 565596 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 565596 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 565596 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 565596 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1560404937174679552
   [junit4]   2> 565596 INFO  
(searcherExecutor-2310-thread-1-processing-n:127.0.0.1:62882_solr 
x:testCreateShardRepFactor_a_replica1 s:a c:testCreateShardRepFactor) 
[n:127.0.0.1:62882_solr c:testCreateShardRepFactor s:a  
x:testCreateShardRepFactor_a_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_a_replica1] Registered new searcher 
Searcher@5c125b14[testCreateShardRepFactor_a_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 565596 INFO  
(searcherExecutor-2309-thread-1-processing-n:127.0.0.1:62883_solr 
x:testCreateShardRepFactor_b_replica1 s:b c:testCreateShardRepFactor) 
[n:127.0.0.1:62883_solr c:testCreateShardRepFactor s:b  
x:testCreateShardRepFactor_b_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_b_replica1] Registered new searcher 
Searcher@6603a795[testCreateShardRepFactor_b_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 565596 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1560404937174679552
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62882/solr/testCreateShardRepFactor_a_replica1/
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62883/solr/testCreateShardRepFactor_b_replica1/
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:62882/solr/testCreateShardRepFactor_a_replica1/ has no replicas
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:62883/solr/testCreateShardRepFactor_b_replica1/ has no replicas
   [junit4]   2> 565608 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 565608 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 565616 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62883/solr/testCreateShardRepFactor_b_replica1/ b
   [junit4]   2> 565616 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62882/solr/testCreateShardRepFactor_a_replica1/ a
   [junit4]   2> 565734 INFO  
(zkCallback-1725-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 565734 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 565787 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 565787 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 565790 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2}
 status=0 QTime=1360
   [junit4]   2> 565792 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2}
 status=0 QTime=1363
   [junit4]   2> 565796 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 565900 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 565900 INFO  
(zkCallback-1725-thread-3-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 566296 INFO  
(OverseerCollectionConfigSetProcessor-97525308424323081-127.0.0.1:62882_solr-n_0000000000)
 [n:127.0.0.1:62882_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 566799 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&action=CREATE&wt=javabin&version=2}
 status=0 QTime=2523
   [junit4]   2> 566823 INFO  (qtp1451488343-6275) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createshard with params 
action=CREATESHARD&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 566827 INFO  
(OverseerThreadFactory-2300-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateShardCmd Create shard invoked: {
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "operation":"createshard"}
   [junit4]   2> 566946 INFO  
(zkCallback-1725-thread-3-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 566946 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 567035 INFO  
(OverseerThreadFactory-2300-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateShardCmd Creating shard 
testCreateShardRepFactor_x_replica1 as part of slice x of collection 
testCreateShardRepFactor on 127.0.0.1:62881_solr
   [junit4]   2> 567038 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&name=testCreateShardRepFactor_x_replica1&action=CREATE&numShards=1&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2
   [junit4]   2> 567155 INFO  
(zkCallback-1727-thread-1-processing-n:127.0.0.1:62881_solr) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 567155 INFO  
(zkCallback-1725-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 567155 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 568060 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 568066 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_x_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 568081 WARN  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_x_replica1] default search field 
in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 568082 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 568084 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_x_replica1' 
using configuration from collection testCreateShardRepFactor
   [junit4]   2> 568084 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_x_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/testCreateShardRepFactor_x_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/./testCreateShardRepFactor_x_replica1/data/]
   [junit4]   2> 568142 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 568142 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 568151 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 568151 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 568151 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3269f020[testCreateShardRepFactor_x_replica1] main]
   [junit4]   2> 568153 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 568154 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 568154 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 568154 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1560404939856936960
   [junit4]   2> 568154 INFO  
(searcherExecutor-2319-thread-1-processing-n:127.0.0.1:62881_solr 
x:testCreateShardRepFactor_x_replica1 s:x c:testCreateShardRepFactor) 
[n:127.0.0.1:62881_solr c:testCreateShardRepFactor s:x  
x:testCreateShardRepFactor_x_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_x_replica1] Registered new searcher 
Searcher@3269f020[testCreateShardRepFactor_x_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 568163 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 568163 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 568163 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62881/solr/testCreateShardRepFactor_x_replica1/
   [junit4]   2> 568163 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 568164 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:62881/solr/testCreateShardRepFactor_x_replica1/ has no replicas
   [junit4]   2> 568164 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 568171 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62881/solr/testCreateShardRepFactor_x_replica1/ x
   [junit4]   2> 568277 INFO  
(zkCallback-1725-thread-1-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 568277 INFO  
(zkCallback-1727-thread-1-processing-n:127.0.0.1:62881_solr) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 568277 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCreateShardRepFactor/state.json] for collection 
[testCreateShardRepFactor] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 568326 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 568329 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&name=testCreateShardRepFactor_x_replica1&action=CREATE&numShards=1&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2}
 status=0 QTime=1291
   [junit4]   2> 568330 INFO  
(OverseerThreadFactory-2300-thread-2-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateShardCmd Finished create command on 
all shards for collection: testCreateShardRepFactor
   [junit4]   2> 568333 INFO  (qtp1451488343-6275) [n:127.0.0.1:62884_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CREATESHARD&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2}
 status=0 QTime=1509
   [junit4]   2> 568335 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[902370B23B208B01]) [  
  ] o.a.s.SolrTestCaseJ4 ###Ending testCreateShardRepFactor
   [junit4]   2> 568367 INFO  
(TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[902370B23B208B01])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForImplicitRouter
   [junit4]   2> 568370 INFO  
(TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[902370B23B208B01])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 568375 INFO  (qtp1451488343-6277) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 568379 INFO  
(OverseerThreadFactory-2300-thread-3-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 568380 INFO  
(OverseerCollectionConfigSetProcessor-97525308424323081-127.0.0.1:62882_solr-n_0000000000)
 [n:127.0.0.1:62882_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 568382 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.x.replica1
   [junit4]   2> 568382 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.a.replica1
   [junit4]   2> 568382 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.b.replica1
   [junit4]   2> 568383 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr    ] 
o.a.s.c.SolrCore [testCreateShardRepFactor_x_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2c67c7bd
   [junit4]   2> 568384 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr    ] 
o.a.s.c.SolrCore [testCreateShardRepFactor_a_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3aa21bef
   [junit4]   2> 568384 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr    ] 
o.a.s.c.SolrCore [testCreateShardRepFactor_b_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@32574998
   [junit4]   2> 568386 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.x.replica1
   [junit4]   2> 568387 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.b.replica1
   [junit4]   2> 568388 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.testCreateShardRepFactor.a.replica1
   [junit4]   2> 568393 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_x_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=11
   [junit4]   2> 568395 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_a_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=13
   [junit4]   2> 568396 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_b_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=14
   [junit4]   2> 569126 INFO  (qtp1451488343-6277) [n:127.0.0.1:62884_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2} 
status=0 QTime=751
   [junit4]   2> 569129 INFO  (qtp1451488343-6278) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b,c,d&replicationFactor=5&collection.configName=conf&maxShardsPerNode=6&router.field=shard_s&name=withShardField&router.name=implicit&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 569133 INFO  
(OverseerThreadFactory-2300-thread-4-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
withShardField
   [junit4]   2> 569133 WARN  
(OverseerThreadFactory-2300-thread-4-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.CreateCollectionCmd Specified 
replicationFactor of 5 on collection withShardField 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> 569133 INFO  
(OverseerCollectionConfigSetProcessor-97525308424323081-127.0.0.1:62882_solr-n_0000000000)
 [n:127.0.0.1:62882_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 569346 INFO  (qtp1451488343-6267) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_a_replica5&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2
   [junit4]   2> 569348 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_d_replica3&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2
   [junit4]   2> 569349 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_d_replica4&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2
   [junit4]   2> 569350 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_d_replica5&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2
   [junit4]   2> 569359 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_c_replica2&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2
   [junit4]   2> 569363 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_b_replica2&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2
   [junit4]   2> 569365 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_a_replica2&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2
   [junit4]   2> 569370 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_c_replica4&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2
   [junit4]   2> 569373 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_a_replica1&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2
   [junit4]   2> 569402 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_b_replica3&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2
   [junit4]   2> 569403 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_b_replica5&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2
   [junit4]   2> 569404 INFO  (qtp1021402151-6270) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_d_replica1&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2
   [junit4]   2> 569405 INFO  (qtp921220149-6260) [n:127.0.0.1:62881_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_b_replica1&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2
   [junit4]   2> 569405 INFO  (qtp1451488343-6275) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_b_replica4&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2
   [junit4]   2> 569406 INFO  (qtp1021402151-6271) [n:127.0.0.1:62882_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_a_replica4&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2
   [junit4]   2> 569407 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_c_replica1&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2
   [junit4]   2> 569407 INFO  (qtp1451488343-6279) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_d_replica2&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2
   [junit4]   2> 569408 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_c_replica5&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2
   [junit4]   2> 569408 INFO  (qtp1451488343-6280) [n:127.0.0.1:62884_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_c_replica3&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2
   [junit4]   2> 569409 INFO  (qtp864606050-6289) [n:127.0.0.1:62883_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=withShardField_a_replica3&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2
   [junit4]   2> 569567 INFO  
(zkCallback-1728-thread-1-processing-n:127.0.0.1:62884_solr) 
[n:127.0.0.1:62884_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/withShardField/state.json] for collection [withShardField] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 569567 INFO  
(zkCallback-1726-thread-1-processing-n:127.0.0.1:62883_solr) 
[n:127.0.0.1:62883_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/withShardField/state.json] for collection [withShardField] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 569568 INFO  
(zkCallback-1725-thread-3-processing-n:127.0.0.1:62882_solr) 
[n:127.0.0.1:62882_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/withShardField/state.json] for collection [withShardField] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 569567 INFO  
(zkCallback-1727-thread-1-processing-n:127.0.0.1:62881_solr) 
[n:127.0.0.1:62881_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/withShardField/state.json] for collection [withShardField] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 570382 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570382 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570387 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570395 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.s.IndexSchema 
[withShardField_d_replica3] Schema name=cloud-dynamic
   [junit4]   2> 570395 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.s.IndexSchema 
[withShardField_d_replica5] Schema name=cloud-dynamic
   [junit4]   2> 570434 WARN  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.s.IndexSchema 
[withShardField_d_replica3] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570435 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570438 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570438 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_d_replica3' using configuration from 
collection withShardField
   [junit4]   2> 570439 INFO  (qtp921220149-6258) [n:127.0.0.1:62881_solr 
c:withShardField s:d  x:withShardField_d_replica3] o.a.s.c.SolrCore 
[[withShardField_d_replica3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/withShardField_d_replica3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/./withShardField_d_replica3/data/]
   [junit4]   2> 570440 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570443 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570448 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:withShardField s:b  x:withShardField_b_replica2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570462 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.s.IndexSchema 
[withShardField_a_replica2] Schema name=cloud-dynamic
   [junit4]   2> 570463 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.s.IndexSchema 
[withShardField_c_replica4] Schema name=cloud-dynamic
   [junit4]   2> 570464 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.s.IndexSchema 
[withShardField_c_replica2] Schema name=cloud-dynamic
   [junit4]   2> 570466 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570480 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.s.IndexSchema 
[withShardField_d_replica4] Schema name=cloud-dynamic
   [junit4]   2> 570493 WARN  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.s.IndexSchema 
[withShardField_a_replica2] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570493 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr 
c:withShardField s:b  x:withShardField_b_replica3] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570494 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570494 INFO  (qtp1021402151-6270) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570498 INFO  (qtp864606050-6285) [n:127.0.0.1:62883_solr 
c:withShardField s:b  x:withShardField_b_replica2] o.a.s.s.IndexSchema 
[withShardField_b_replica2] Schema name=cloud-dynamic
   [junit4]   2> 570499 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570500 INFO  (qtp864606050-6289) [n:127.0.0.1:62883_solr 
c:withShardField s:a  x:withShardField_a_replica3] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570504 INFO  (qtp1021402151-6271) [n:127.0.0.1:62882_solr 
c:withShardField s:a  x:withShardField_a_replica4] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570505 INFO  (qtp864606050-6286) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570506 INFO  (qtp1451488343-6279) [n:127.0.0.1:62884_solr 
c:withShardField s:d  x:withShardField_d_replica2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570524 WARN  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.s.IndexSchema 
[withShardField_c_replica4] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570526 INFO  (qtp1451488343-6275) [n:127.0.0.1:62884_solr 
c:withShardField s:b  x:withShardField_b_replica4] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570526 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570526 INFO  (qtp921220149-6260) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570535 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_a_replica2' using configuration from 
collection withShardField
   [junit4]   2> 570535 INFO  (qtp921220149-6254) [n:127.0.0.1:62881_solr 
c:withShardField s:a  x:withShardField_a_replica2] o.a.s.c.SolrCore 
[[withShardField_a_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/withShardField_a_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/./withShardField_a_replica2/data/]
   [junit4]   2> 570536 INFO  (qtp1451488343-6280) [n:127.0.0.1:62884_solr 
c:withShardField s:c  x:withShardField_c_replica3] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570541 INFO  (qtp1451488343-6267) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica5] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 570543 INFO  (qtp1451488343-6279) [n:127.0.0.1:62884_solr 
c:withShardField s:d  x:withShardField_d_replica2] o.a.s.s.IndexSchema 
[withShardField_d_replica2] Schema name=cloud-dynamic
   [junit4]   2> 570546 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.s.IndexSchema 
[withShardField_c_replica5] Schema name=cloud-dynamic
   [junit4]   2> 570554 INFO  (qtp921220149-6260) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica1] o.a.s.s.IndexSchema 
[withShardField_b_replica1] Schema name=cloud-dynamic
   [junit4]   2> 570563 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.s.IndexSchema 
[withShardField_b_replica5] Schema name=cloud-dynamic
   [junit4]   2> 570566 INFO  (qtp1451488343-6267) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica5] o.a.s.s.IndexSchema 
[withShardField_a_replica5] Schema name=cloud-dynamic
   [junit4]   2> 570566 INFO  (qtp1451488343-6280) [n:127.0.0.1:62884_solr 
c:withShardField s:c  x:withShardField_c_replica3] o.a.s.s.IndexSchema 
[withShardField_c_replica3] Schema name=cloud-dynamic
   [junit4]   2> 570569 INFO  (qtp864606050-6289) [n:127.0.0.1:62883_solr 
c:withShardField s:a  x:withShardField_a_replica3] o.a.s.s.IndexSchema 
[withShardField_a_replica3] Schema name=cloud-dynamic
   [junit4]   2> 570576 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.s.IndexSchema 
[withShardField_a_replica1] Schema name=cloud-dynamic
   [junit4]   2> 570580 INFO  (qtp1451488343-6275) [n:127.0.0.1:62884_solr 
c:withShardField s:b  x:withShardField_b_replica4] o.a.s.s.IndexSchema 
[withShardField_b_replica4] Schema name=cloud-dynamic
   [junit4]   2> 570593 WARN  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.s.IndexSchema 
[withShardField_c_replica2] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570594 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570602 WARN  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.s.IndexSchema 
[withShardField_d_replica4] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570603 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570604 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_d_replica4' using configuration from 
collection withShardField
   [junit4]   2> 570605 INFO  (qtp864606050-6287) [n:127.0.0.1:62883_solr 
c:withShardField s:d  x:withShardField_d_replica4] o.a.s.c.SolrCore 
[[withShardField_d_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/withShardField_d_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/./withShardField_d_replica4/data/]
   [junit4]   2> 570615 WARN  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.s.IndexSchema 
[withShardField_b_replica5] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570615 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570616 WARN  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.s.IndexSchema 
[withShardField_a_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570616 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_b_replica5' using configuration from 
collection withShardField
   [junit4]   2> 570617 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570617 INFO  (qtp921220149-6257) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica5] o.a.s.c.SolrCore 
[[withShardField_b_replica5] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/withShardField_b_replica5],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/./withShardField_b_replica5/data/]
   [junit4]   2> 570617 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_a_replica1' using configuration from 
collection withShardField
   [junit4]   2> 570618 INFO  (qtp1451488343-6273) [n:127.0.0.1:62884_solr 
c:withShardField s:a  x:withShardField_a_replica1] o.a.s.c.SolrCore 
[[withShardField_a_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node3/withShardField_a_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node3/./withShardField_a_replica1/data/]
   [junit4]   2> 570618 WARN  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.s.IndexSchema 
[withShardField_d_replica5] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570619 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570620 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570621 INFO  (qtp1021402151-6270) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica1] o.a.s.s.IndexSchema 
[withShardField_d_replica1] Schema name=cloud-dynamic
   [junit4]   2> 570622 INFO  (qtp1021402151-6271) [n:127.0.0.1:62882_solr 
c:withShardField s:a  x:withShardField_a_replica4] o.a.s.s.IndexSchema 
[withShardField_a_replica4] Schema name=cloud-dynamic
   [junit4]   2> 570622 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr 
c:withShardField s:b  x:withShardField_b_replica3] o.a.s.s.IndexSchema 
[withShardField_b_replica3] Schema name=cloud-dynamic
   [junit4]   2> 570635 WARN  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.s.IndexSchema 
[withShardField_c_replica5] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570635 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570636 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_c_replica2' using configuration from 
collection withShardField
   [junit4]   2> 570637 INFO  (qtp1021402151-6265) [n:127.0.0.1:62882_solr 
c:withShardField s:c  x:withShardField_c_replica2] o.a.s.c.SolrCore 
[[withShardField_c_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/withShardField_c_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/./withShardField_c_replica2/data/]
   [junit4]   2> 570638 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_d_replica5' using configuration from 
collection withShardField
   [junit4]   2> 570638 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_c_replica5' using configuration from 
collection withShardField
   [junit4]   2> 570638 WARN  (qtp1021402151-6266) [n:127.0.0.1:62882_solr 
c:withShardField s:b  x:withShardField_b_replica3] o.a.s.s.IndexSchema 
[withShardField_b_replica3] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570638 INFO  (qtp1021402151-6268) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica5] o.a.s.c.SolrCore 
[[withShardField_d_replica5] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/withShardField_d_replica5],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node4/./withShardField_d_replica5/data/]
   [junit4]   2> 570638 INFO  (qtp864606050-6288) [n:127.0.0.1:62883_solr 
c:withShardField s:c  x:withShardField_c_replica5] o.a.s.c.SolrCore 
[[withShardField_c_replica5] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/withShardField_c_replica5],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node1/./withShardField_c_replica5/data/]
   [junit4]   2> 570638 INFO  (qtp1021402151-6266) [n:127.0.0.1:62882_solr 
c:withShardField s:b  x:withShardField_b_replica3] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570667 WARN  (qtp1021402151-6270) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica1] o.a.s.s.IndexSchema 
[withShardField_d_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570674 INFO  (qtp1021402151-6270) [n:127.0.0.1:62882_solr 
c:withShardField s:d  x:withShardField_d_replica1] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 570676 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.c.CoreContainer 
Creating SolrCore 'withShardField_c_replica4' using configuration from 
collection withShardField
   [junit4]   2> 570676 WARN  (qtp921220149-6260) [n:127.0.0.1:62881_solr 
c:withShardField s:b  x:withShardField_b_replica1] o.a.s.s.IndexSchema 
[withShardField_b_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 570676 INFO  (qtp921220149-6256) [n:127.0.0.1:62881_solr 
c:withShardField s:c  x:withShardField_c_replica4] o.a.s.c.SolrCore 
[[withShardField_c_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/withShardField_c_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_902370B23B208B01-001/tempDir-001/node2/./withShardField_c_replica4/data/]
   [junit4]   2> 570676 INFO  (qtp921220149-6260) [n:127.0.0.1:62881_solr 
c:withShardF

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

e no versions - we can't sync in that case - we were active before, so become 
leader anyway
   [junit4]   2> 2280494 INFO  
(zkCallback-3166-thread-2-processing-n:127.0.0.1:51492_) [n:127.0.0.1:51492_ 
c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore 
[collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@14ac9868
   [junit4]   2> 2280530 INFO  
(zkCallback-3166-thread-2-processing-n:127.0.0.1:51492_) [n:127.0.0.1:51492_ 
c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.m.SolrMetricManager 
Closing metric reporters for: solr.core.collection1
   [junit4]   2> 2281919 WARN  
(zkCallback-3166-thread-4-processing-n:127.0.0.1:51492_) [n:127.0.0.1:51492_    
] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to 
ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2281920 INFO  
(TEST-MultiThreadedOCPTest.test-seed#[902370B23B208B01]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2281921 INFO  
(TEST-MultiThreadedOCPTest.test-seed#[902370B23B208B01]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@7fe9eac8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2281921 INFO  
(TEST-MultiThreadedOCPTest.test-seed#[902370B23B208B01]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@37fe0caa{/,null,UNAVAILABLE}
   [junit4]   2> 2281923 INFO  
(TEST-MultiThreadedOCPTest.test-seed#[902370B23B208B01]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:51467 51467
   [junit4]   2> 2281942 INFO  (Thread-3830) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:51467 51467
   [junit4]   2> 2285685 WARN  (Thread-3830) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2>        6       /solr/clusterprops.json
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        5       /solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/clusterstate.json
   [junit4]   2>        4       /solr/collections/ocptest_shardsplit2/state.json
   [junit4]   2>        4       /solr/collections/ocptest1/state.json
   [junit4]   2>        4       /solr/collections/collection1/state.json
   [junit4]   2>        4       /solr/collections/ocptest3/state.json
   [junit4]   2>        4       /solr/collections/ocptest2/state.json
   [junit4]   2>        4       /solr/collections/ocptest4/state.json
   [junit4]   2>        4       /solr/collections/ocptest_shardsplit/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        62      /solr/overseer/queue
   [junit4]   2>        19      /solr/overseer/queue-work
   [junit4]   2>        16      /solr/overseer/collection-queue-work
   [junit4]   2>        6       /solr/live_nodes
   [junit4]   2>        6       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=MultiThreadedOCPTest -Dtests.method=test 
-Dtests.seed=902370B23B208B01 -Dtests.slow=true -Dtests.locale=fr 
-Dtests.timezone=ROK -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 49.4s J0 | MultiThreadedOCPTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Task 3001 did not 
complete, final state: FAILED expected same:<COMPLETED> was not:<FAILED>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([902370B23B208B01:18774F6895DCE6F9]:0)
   [junit4]    >        at 
org.apache.solr.cloud.MultiThreadedOCPTest.testDeduplicationOfSubmittedTasks(MultiThreadedOCPTest.java:250)
   [junit4]    >        at 
org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:64)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2285773 INFO  
(SUITE-MultiThreadedOCPTest-seed#[902370B23B208B01]-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.MultiThreadedOCPTest_902370B23B208B01-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62), 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=fr, timezone=ROK
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=79531176,total=494927872
   [junit4]   2> NOTE: All tests run in this JVM: [TestLRUCache, 
IndexBasedSpellCheckerTest, ResponseHeaderTest, TestFieldCollectionResource, 
TestTolerantUpdateProcessorRandomCloud, TestPostingsSolrHighlighter, 
TestCollectionAPIs, PrimUtilsTest, CollectionReloadTest, HighlighterTest, 
CoreAdminRequestStatusTest, SharedFSAutoReplicaFailoverUtilsTest, 
TestSolrCLIRunExample, HdfsDirectoryTest, SubstringBytesRefFilterTest, 
FacetPivotSmallTest, DirectSolrConnectionTest, CursorPagingTest, 
TermsComponentTest, CollectionTooManyReplicasTest, 
OpenExchangeRatesOrgProviderTest, TestRTGBase, TestSolrDeletionPolicy1, 
HighlighterMaxOffsetTest, TestDistribDocBasedVersion, TestCloudPivotFacet, 
TestFastOutputStream, TestHighlightDedupGrouping, 
TestExclusionRuleCollectionAccess, HdfsRecoverLeaseTest, TestOrdValues, 
TestNonDefinedSimilarityFactory, SyncSliceTest, TestScoreJoinQPScore, 
ExplicitHLLTest, TestIBSimilarityFactory, TestGeoJSONResponseWriter, 
TestComponentsName, CheckHdfsIndexTest, TestSubQueryTransformerDistrib, 
SolrSlf4jReporterTest, CleanupOldIndexTest, DistributedSpellCheckComponentTest, 
TestRecoveryHdfs, QueryElevationComponentTest, MissingSegmentRecoveryTest, 
BJQParserTest, DirectSolrSpellCheckerTest, CdcrUpdateLogTest, 
DocValuesMissingTest, TestOnReconnectListenerSupport, PingRequestHandlerTest, 
TestSort, DisMaxRequestHandlerTest, DistanceUnitsTest, TestLazyCores, 
TestSolrQueryParserResource, SolrGraphiteReporterTest, ShardSplitTest, 
ZkControllerTest, TestSchemaNameResource, HdfsBasicDistributedZk2Test, 
TestFieldCacheReopen, TestFaceting, FileUtilsTest, SuggestComponentTest, 
SolrTestCaseJ4Test, TestCloudRecovery, TestSweetSpotSimilarityFactory, 
XmlUpdateRequestHandlerTest, HLLSerializationTest, DeleteInactiveReplicaTest, 
TestUseDocValuesAsStored, PluginInfoTest, ManagedSchemaRoundRobinCloudTest, 
ZkStateReaderTest, TestHdfsUpdateLog, TestLockTree, SOLR749Test, 
TestDefaultStatsCache, TestCopyFieldCollectionResource, TestSolrDynamicMBean, 
TestLeaderElectionWithEmptyReplica, TestSubQueryTransformer, 
TestRemoteStreaming, ReplicaListTransformerTest, UnloadDistributedZkTest, 
DOMUtilTest, InfoHandlerTest, JSONWriterTest, AliasIntegrationTest, 
DirectUpdateHandlerTest, TestCloudManagedSchema, ConfigSetsAPITest, 
TestBulkSchemaAPI, ForceLeaderTest, SuggesterFSTTest, 
AsyncCallRequestStatusResponseTest, TestPseudoReturnFields, 
TolerantUpdateProcessorTest, UniqFieldsUpdateProcessorFactoryTest, 
MultiTermTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
AnalyticsMergeStrategyTest, PeerSyncWithIndexFingerprintCachingTest, 
JvmMetricsTest, DocValuesTest, StatsReloadRaceTest, TestCorePropertiesReload, 
ReplaceNodeTest, TestReplicaProperties, LeaderFailureAfterFreshStartTest, 
TestExactSharedStatsCache, DistributedFacetPivotLargeTest, CloudMLTQParserTest, 
HdfsUnloadDistributedZkTest, TestWordDelimiterFilterFactory, QueryEqualityTest, 
SimpleFacetsTest, TestCoreContainer, PrimitiveFieldTypeTest, TestCSVLoader, 
SpatialHeatmapFacetsTest, TestClassicSimilarityFactory, TestBinaryField, 
TestStressRecovery, TestJmxIntegration, TestLRUStatsCache, TestTrieFacet, 
TestReplicationHandlerBackup, TestHashPartitioner, CreateCollectionCleanupTest, 
DistributedFacetPivotWhiteBoxTest, OverseerRolesTest, TestSegmentSorting, 
TestMacros, BigEndianAscendingWordSerializerTest, TestAnalyzedSuggestions, 
TestCryptoKeys, TestPerFieldSimilarityWithDefaultOverride, 
TestObjectReleaseTracker, SmileWriterTest, TestPerFieldSimilarity, 
ShardRoutingTest, TriLevelCompositeIdRoutingTest, XsltUpdateRequestHandlerTest, 
SynonymTokenizerTest, BlockDirectoryTest, TestTrackingShardHandlerFactory, 
TestSQLHandlerNonCloud, StandardRequestHandlerTest, TimeZoneUtilsTest, 
AnalysisAfterCoreReloadTest, TestCustomSort, OverseerTaskQueueTest, 
TestFieldCacheSort, CollectionsAPISolrJTest, TestDocTermOrds, 
TestDelegationWithHadoopAuth, OutOfBoxZkACLAndCredentialsProvidersTest, 
SampleTest, TestSchemaManager, TestRandomDVFaceting, SolrXmlInZkTest, 
TestSolrCloudSnapshots, ZkNodePropsTest, TestStressVersions, 
TestJavabinTupleStreamParser, ResponseLogComponentTest, TestStressLucene, 
OverseerTest, ClusterStateUpdateTest, LeaderElectionTest, 
TestDistributedSearch, ShowFileRequestHandlerTest, 
DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, 
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterTSTTest, TestTrie, 
SpatialFilterTest, WordBreakSolrSpellCheckerTest, 
SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, 
TestAtomicUpdateErrorCases, SortByFunctionTest, DocValuesMultiTest, 
DistanceFunctionTest, CacheHeaderTest, DocumentAnalysisRequestHandlerTest, 
FileBasedSpellCheckerTest, DocumentBuilderTest, 
PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, 
FastVectorHighlighterTest, LoggingHandlerTest, TestCollationField, 
ReturnFieldsTest, MBeansHandlerTest, TestLFUCache, TestCodecSupport, 
TestLMJelinekMercerSimilarityFactory, TestFastWriter, URLClassifyProcessorTest, 
ChaosMonkeyNothingIsSafeTest, TestTolerantSearch, 
TestEmbeddedSolrServerConstructors, TestJettySolrRunner, ConnectionReuseTest, 
BaseCdcrDistributedZkTest, CdcrBootstrapTest, 
DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DistribCursorPagingTest, 
DistribJoinFromCollectionTest, DocValuesNotIndexedTest, HttpPartitionTest, 
MultiThreadedOCPTest]
   [junit4] Completed [498/696 (2!)] on J0 in 49.48s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 63382 lines...]

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

Reply via email to