Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/24/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC

4 tests failed.
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([743B5868241FAF6:6DA23BEDBFDB4C8E]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
        at 
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
        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:748)


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

Error Message:
Could not find collection : deletenodetest_coll

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : 
deletenodetest_coll
        at 
__randomizedtesting.SeedInfo.seed([743B5868241FAF6:8F178A5C2CBD970E]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
        at org.apache.solr.cloud.DeleteNodeTest.test(DeleteNodeTest.java:78)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


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

Error Message:
{}

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


FAILED:  
org.apache.solr.request.TestStreamBody.testStreamBodyDefaultAndConfigApi

Error Message:
Error from server at http://127.0.0.1:57108/solr/collection1: Expected mime 
type application/octet-stream but got text/html. <html> <head> <meta 
http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 
500 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing 
/solr/collection1/update. Reason: <pre>    java.lang.NoClassDefFoundError: 
org/apache/solr/servlet/HttpSolrCall$1</pre></p> <hr /><a 
href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.14.v20161028</a><hr/> 
</body> </html> 

Stack Trace:
java.lang.AssertionError: Error from server at 
http://127.0.0.1:57108/solr/collection1: Expected mime type 
application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 </title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /solr/collection1/update. Reason:
<pre>    java.lang.NoClassDefFoundError: 
org/apache/solr/servlet/HttpSolrCall$1</pre></p>
<hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 
9.3.14.v20161028</a><hr/>
</body>
</html>

        at 
__randomizedtesting.SeedInfo.seed([743B5868241FAF6:BCD65B55CF90DEE0]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.request.TestStreamBody.testStreamBodyDefaultAndConfigApi(TestStreamBody.java:126)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12419 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/init-core-data-001
   [junit4]   2> 1901207 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1901213 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-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> 1901214 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001
   [junit4]   2> 1901215 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1901215 INFO  (Thread-2854) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1901215 INFO  (Thread-2854) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1901221 ERROR (Thread-2854) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1901323 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:53681
   [junit4]   2> 1901358 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1901358 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1901359 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1901359 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1901361 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4b62878{/solr,null,AVAILABLE}
   [junit4]   2> 1901362 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2d9c7670{HTTP/1.1,[http/1.1]}{127.0.0.1:53683}
   [junit4]   2> 1901362 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.e.j.s.Server Started @1904979ms
   [junit4]   2> 1901362 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53683}
   [junit4]   2> 1901362 ERROR (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-10T04:41:37.381Z
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@721302a0{/solr,null,AVAILABLE}
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@26b0f5c1{HTTP/1.1,[http/1.1]}{127.0.0.1:53684}
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.e.j.s.Server Started @1904980ms
   [junit4]   2> 1901363 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53684}
   [junit4]   2> 1901363 ERROR (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1901364 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1901364 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1901364 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1901364 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-10T04:41:37.382Z
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7c040006{/solr,null,AVAILABLE}
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5340e982{/solr,null,AVAILABLE}
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5c414341{HTTP/1.1,[http/1.1]}{127.0.0.1:53686}
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.e.j.s.Server Started @1904982ms
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@36f5651c{HTTP/1.1,[http/1.1]}{127.0.0.1:53687}
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53686}
   [junit4]   2> 1901365 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.e.j.s.Server Started @1904982ms
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53687}
   [junit4]   2> 1901366 ERROR (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1901366 ERROR (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-10T04:41:37.384Z
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1901366 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-10T04:41:37.384Z
   [junit4]   2> 1901371 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1901409 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1901409 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1901417 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1901422 INFO  (jetty-launcher-2340-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53681/solr
   [junit4]   2> 1901427 INFO  (jetty-launcher-2340-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53681/solr
   [junit4]   2> 1901431 INFO  (jetty-launcher-2340-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53681/solr
   [junit4]   2> 1901434 INFO  (jetty-launcher-2340-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53681/solr
   [junit4]   2> 1901477 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1901480 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:53687_solr
   [junit4]   2> 1901490 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.Overseer Overseer 
(id=98281828990124043-127.0.0.1:53687_solr-n_0000000000) starting
   [junit4]   2> 1901503 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1901505 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53687_solr
   [junit4]   2> 1901506 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53686_solr
   [junit4]   2> 1901509 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1901510 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1901547 INFO  
(zkCallback-2364-thread-1-processing-n:127.0.0.1:53686_solr) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1901653 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1901657 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1901660 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53683_solr
   [junit4]   2> 1901664 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1901676 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1901693 INFO  
(zkCallback-2364-thread-1-processing-n:127.0.0.1:53686_solr) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1901707 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1901710 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1901713 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53684_solr
   [junit4]   2> 1901716 INFO  
(zkCallback-2364-thread-1-processing-n:127.0.0.1:53686_solr) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1901716 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1901717 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1901720 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1901834 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53684.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901854 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53683.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901857 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53687.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901859 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53684.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901859 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53684.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901860 INFO  (jetty-launcher-2340-thread-3) 
[n:127.0.0.1:53684_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node3/.
   [junit4]   2> 1901861 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53687.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901861 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53687.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901864 INFO  (jetty-launcher-2340-thread-2) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node2/.
   [junit4]   2> 1901867 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53683.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901867 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53683.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901868 INFO  (jetty-launcher-2340-thread-1) 
[n:127.0.0.1:53683_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node1/.
   [junit4]   2> 1901915 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53686.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901918 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53686.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901918 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53686.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1901919 INFO  (jetty-launcher-2340-thread-4) 
[n:127.0.0.1:53686_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node4/.
   [junit4]   2> 1901975 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1901977 INFO  
(SUITE-CustomCollectionTest-seed#[743B5868241FAF6]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53681/solr ready
   [junit4]   2> 1902004 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[743B5868241FAF6]) [   
 ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShardRepFactor
   [junit4]   2> 1902005 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[743B5868241FAF6]) [   
 ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1902011 INFO  (qtp515883284-15309) [n:127.0.0.1:53686_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&nrtReplicas=1&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1902013 INFO  
(OverseerThreadFactory-6778-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
testCreateShardRepFactor
   [junit4]   2> 1902133 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"testCreateShardRepFactor_a_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53684/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1902135 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"testCreateShardRepFactor_b_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53683/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1902358 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1902358 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1902358 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1902358 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1902465 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1902466 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1902466 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1902466 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1903379 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 1903428 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 1903429 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.IndexSchema 
[testCreateShardRepFactor_a_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1903438 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.s.IndexSchema 
[testCreateShardRepFactor_b_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1903481 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.IndexSchema Loaded schema 
cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1903481 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'testCreateShardRepFactor_a_replica_n1' using configuration from 
collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1903481 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_53684.solr.core.testCreateShardRepFactor.a.replica_n1' 
(registry 'solr.core.testCreateShardRepFactor.a.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1903482 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1903482 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.s.IndexSchema Loaded schema 
cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1903482 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore 
[[testCreateShardRepFactor_a_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node3/testCreateShardRepFactor_a_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node3/./testCreateShardRepFactor_a_replica_n1/data/]
   [junit4]   2> 1903482 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'testCreateShardRepFactor_b_replica_n1' using configuration from 
collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1903482 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_53683.solr.core.testCreateShardRepFactor.b.replica_n1' 
(registry 'solr.core.testCreateShardRepFactor.b.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1903482 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1903482 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SolrCore 
[[testCreateShardRepFactor_b_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node1/testCreateShardRepFactor_b_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node1/./testCreateShardRepFactor_b_replica_n1/data/]
   [junit4]   2> 1903555 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1903555 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1903555 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1903555 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1903557 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1903557 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1903557 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1903557 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1903559 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2026905[testCreateShardRepFactor_b_replica_n1] main]
   [junit4]   2> 1903559 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2ac15a4a[testCreateShardRepFactor_a_replica_n1] main]
   [junit4]   2> 1903560 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1903560 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.r.ManagedResourceStorage Loaded 
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1903561 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.h.ReplicationHandler Commits 
will be reserved for  10000
   [junit4]   2> 1903561 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1572509266297749504
   [junit4]   2> 1903563 INFO  
(searcherExecutor-6788-thread-1-processing-n:127.0.0.1:53683_solr 
x:testCreateShardRepFactor_b_replica_n1 s:b c:testCreateShardRepFactor 
r:core_node2) [n:127.0.0.1:53683_solr c:testCreateShardRepFactor s:b 
r:core_node2 x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_b_replica_n1] Registered new searcher 
Searcher@2026905[testCreateShardRepFactor_b_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:53683/solr/testCreateShardRepFactor_b_replica_n1/
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SyncStrategy Sync Success - 
now sync replicas to me
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:53683/solr/testCreateShardRepFactor_b_replica_n1/ has no 
replicas
   [junit4]   2> 1903569 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 1903572 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1903573 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.r.ManagedResourceStorage Loaded 
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1903574 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.h.ReplicationHandler Commits 
will be reserved for  10000
   [junit4]   2> 1903574 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1572509266311380992
   [junit4]   2> 1903575 INFO  
(searcherExecutor-6787-thread-1-processing-n:127.0.0.1:53684_solr 
x:testCreateShardRepFactor_a_replica_n1 s:a c:testCreateShardRepFactor 
r:core_node1) [n:127.0.0.1:53684_solr c:testCreateShardRepFactor s:a 
r:core_node1 x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_a_replica_n1] Registered new searcher 
Searcher@2ac15a4a[testCreateShardRepFactor_a_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1903576 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:53683/solr/testCreateShardRepFactor_b_replica_n1/ b
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:53684/solr/testCreateShardRepFactor_a_replica_n1/
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy Sync Success - 
now sync replicas to me
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:53684/solr/testCreateShardRepFactor_a_replica_n1/ has no 
replicas
   [junit4]   2> 1903588 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 1903593 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:53684/solr/testCreateShardRepFactor_a_replica_n1/ a
   [junit4]   2> 1903705 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1903705 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1903705 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1903705 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1903751 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.c.ZkController I am the leader, 
no recovery necessary
   [junit4]   2> 1903755 INFO  (qtp894488147-15299) [n:127.0.0.1:53683_solr 
c:testCreateShardRepFactor s:b r:core_node2 
x:testCreateShardRepFactor_b_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1397
   [junit4]   2> 1903755 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.c.ZkController I am the leader, 
no recovery necessary
   [junit4]   2> 1903758 INFO  (qtp562658425-15322) [n:127.0.0.1:53684_solr 
c:testCreateShardRepFactor s:a r:core_node1 
x:testCreateShardRepFactor_a_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica_n1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1400
   [junit4]   2> 1903764 INFO  (qtp515883284-15309) [n:127.0.0.1:53686_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> 1903868 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1903868 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1903868 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1903868 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1904026 INFO  
(OverseerCollectionConfigSetProcessor-98281828990124043-127.0.0.1:53687_solr-n_0000000000)
 [n:127.0.0.1:53687_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> 1904778 INFO  (qtp515883284-15309) [n:127.0.0.1:53686_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&nrtReplicas=1&action=CREATE&wt=javabin&version=2}
 status=0 QTime=2766
   [junit4]   2> 1904780 INFO  (qtp515883284-15314) [n:127.0.0.1:53686_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> 1904801 INFO  
(OverseerThreadFactory-6778-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CreateShardCmd Create shard invoked: {
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "operation":"createshard"}
   [junit4]   2> 1904923 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1904923 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1904923 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1904923 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1905809 INFO  
(OverseerThreadFactory-6778-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CreateShardCmd Creating replica 
testCreateShardRepFactor_x_replica_n1 as part of slice x of collection 
testCreateShardRepFactor on 127.0.0.1:53687_solr
   [junit4]   2> 1905811 INFO  
(OverseerThreadFactory-6778-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:53687_solr for creating new replica
   [junit4]   2> 1905813 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"addreplica",
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "core":"testCreateShardRepFactor_x_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53687/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:53687_solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1905924 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1905924 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1905924 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1905924 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906033 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&name=testCreateShardRepFactor_x_replica_n1&action=CREATE&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1906034 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1906054 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.1.0
   [junit4]   2> 1906062 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.s.IndexSchema 
[testCreateShardRepFactor_x_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1906115 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.s.IndexSchema Loaded schema 
cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1906115 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'testCreateShardRepFactor_x_replica_n1' using configuration from 
collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1906115 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_53687.solr.core.testCreateShardRepFactor.x.replica_n1' 
(registry 'solr.core.testCreateShardRepFactor.x.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@754eaae1
   [junit4]   2> 1906115 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1906115 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SolrCore 
[[testCreateShardRepFactor_x_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node2/testCreateShardRepFactor_x_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_743B5868241FAF6-001/tempDir-001/node2/./testCreateShardRepFactor_x_replica_n1/data/]
   [junit4]   2> 1906150 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906150 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906151 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906151 INFO  
(zkCallback-2361-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906151 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906152 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906213 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1906213 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1906215 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1906215 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1906216 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6e2d4f50[testCreateShardRepFactor_x_replica_n1] main]
   [junit4]   2> 1906218 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1906218 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.r.ManagedResourceStorage Loaded 
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1906219 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.h.ReplicationHandler Commits 
will be reserved for  10000
   [junit4]   2> 1906219 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1572509269084864512
   [junit4]   2> 1906220 INFO  
(searcherExecutor-6797-thread-1-processing-n:127.0.0.1:53687_solr 
x:testCreateShardRepFactor_x_replica_n1 s:x c:testCreateShardRepFactor 
r:core_node3) [n:127.0.0.1:53687_solr c:testCreateShardRepFactor s:x 
r:core_node3 x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_x_replica_n1] Registered new searcher 
Searcher@6e2d4f50[testCreateShardRepFactor_x_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:53687/solr/testCreateShardRepFactor_x_replica_n1/
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SyncStrategy Sync Success - 
now sync replicas to me
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:53687/solr/testCreateShardRepFactor_x_replica_n1/ has no 
replicas
   [junit4]   2> 1906227 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 1906233 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:53687/solr/testCreateShardRepFactor_x_replica_n1/ x
   [junit4]   2> 1906338 INFO  
(zkCallback-2361-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906338 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906338 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906338 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906338 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906338 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906353 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.c.ZkController I am the leader, 
no recovery necessary
   [junit4]   2> 1906359 INFO  (qtp1568706350-15312) [n:127.0.0.1:53687_solr 
c:testCreateShardRepFactor s:x r:core_node3 
x:testCreateShardRepFactor_x_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&name=testCreateShardRepFactor_x_replica_n1&action=CREATE&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=325
   [junit4]   2> 1906360 INFO  
(OverseerThreadFactory-6778-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CreateShardCmd Finished create command on 
all shards for collection: testCreateShardRepFactor
   [junit4]   2> 1906362 INFO  (qtp515883284-15314) [n:127.0.0.1:53686_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=1582
   [junit4]   2> 1906365 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[743B5868241FAF6]) [   
 ] o.a.s.SolrTestCaseJ4 ###Ending testCreateShardRepFactor
   [junit4]   2> 1906418 INFO  
(TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[743B5868241FAF6])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForImplicitRouter
   [junit4]   2> 1906420 INFO  
(TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[743B5868241FAF6])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1906421 INFO  (qtp515883284-15315) [n:127.0.0.1:53686_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> 1906428 INFO  
(OverseerThreadFactory-6778-thread-3-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1906431 INFO  (qtp894488147-15300) [n:127.0.0.1:53683_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.b.replica_n1, tag=null
   [junit4]   2> 1906431 INFO  (qtp562658425-15323) [n:127.0.0.1:53684_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.a.replica_n1, tag=null
   [junit4]   2> 1906431 INFO  
(OverseerCollectionConfigSetProcessor-98281828990124043-127.0.0.1:53687_solr-n_0000000000)
 [n:127.0.0.1:53687_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> 1906431 INFO  (qtp1568706350-15313) [n:127.0.0.1:53687_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.x.replica_n1, tag=null
   [junit4]   2> 1906461 INFO  
(zkCallback-2350-thread-1-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906461 INFO  
(zkCallback-2358-thread-2-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906461 INFO  
(zkCallback-2350-thread-2-processing-n:127.0.0.1:53683_solr) 
[n:127.0.0.1:53683_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> 1906461 INFO  
(zkCallback-2361-thread-1-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906461 INFO  
(zkCallback-2361-thread-2-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_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> 1906461 INFO  
(zkCallback-2358-thread-1-processing-n:127.0.0.1:53684_solr) 
[n:127.0.0.1:53684_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> 1906475 INFO  (qtp562658425-15323) [n:127.0.0.1:53684_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_a_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@8cebabb
   [junit4]   2> 1906476 INFO  (qtp1568706350-15313) [n:127.0.0.1:53687_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_x_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6f945466
   [junit4]   2> 1906479 INFO  (qtp894488147-15300) [n:127.0.0.1:53683_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_b_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@487d7203
   [junit4]   2> 1906481 INFO  (qtp1568706350-15313) [n:127.0.0.1:53687_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.x.replica_n1, tag=1871991910
   [junit4]   2> 1906481 INFO  (qtp1568706350-15313) [n:127.0.0.1:53687_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.x.leader, tag=1871991910
   [junit4]   2> 1906481 INFO  (qtp562658425-15323) [n:127.0.0.1:53684_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.a.replica_n1, tag=147765947
   [junit4]   2> 1906481 INFO  (qtp562658425-15323) [n:127.0.0.1:53684_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.a.leader, tag=147765947
   [junit4]   2> 1906485 INFO  (qtp894488147-15300) [n:127.0.0.1:53683_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.b.replica_n1, tag=1216180739
   [junit4]   2> 1906485 INFO  (qtp894488147-15300) [n:127.0.0.1:53683_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.b.leader, tag=1216180739
   [junit4]   2> 1906490 INFO  (qtp562658425-15323) [n:127.0.0.1:53684_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_a_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=59
   [junit4]   2> 1906491 INFO  (qtp1568706350-15313) [n:127.0.0.1:53687_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_x_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=60
   [junit4]   2> 1906493 INFO  (qtp894488147-15300) [n:127.0.0.1:53683_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_b_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=63
   [junit4]   2> 1907224 INFO  (qtp515883284-15315) [n:127.0.0.1:53686_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2} 
status=0 QTime=802
   [junit4]   2> 1907225 INFO  (qtp515883284-15316) [n:127.0.0.1:53686_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b,c,d&replicationFactor=4&collection.configName=conf&maxShardsPerNode=5&router.field=shard_s&name=withShardField&router.name=implicit&nrtReplicas=4&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1907228 INFO  
(OverseerThreadFactory-6778-thread-4-processing-n:127.0.0.1:53687_solr) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
withShardField
   [junit4]   2> 1907229 INFO  
(OverseerCollectionConfigSetProcessor-98281828990124043-127.0.0.1:53687_solr-n_0000000000)
 [n:127.0.0.1:53687_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> 1907449 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53684/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907452 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53683/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907455 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53686/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907460 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"a",
   [junit4]   2>   "core":"withShardField_a_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53687/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907463 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53684/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907465 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53683/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907467 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53686/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907469 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"b",
   [junit4]   2>   "core":"withShardField_b_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53687/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907471 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53684/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907472 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53683/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907478 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]   2>   "shard":"c",
   [junit4]   2>   "core":"withShardField_c_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:53686/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1907479 INFO  
(OverseerStateUpdate-98281828990124043-127.0.0.1:53687_solr-n_0000000000) 
[n:127.0.0.1:53687_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"withShardField",
   [junit4]  

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

.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 2799121 INFO  (qtp172632215-23144) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&echoHandler=true&qt=/update&stream.body=<delete><query>*:*</query></delete>&wt=javabin&version=2}
 status=400 QTime=0
   [junit4]   2> 2799122 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@deb49cf{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2799122 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=288333807
   [junit4]   2> 2799123 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2799124 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2799125 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2799125 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2799126 INFO  (coreCloseExecutor-9940-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@662142b8
   [junit4]   2> 2799126 INFO  (coreCloseExecutor-9940-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1713455800
   [junit4]   2> 2799127 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4dcc4511{/solr,null,UNAVAILABLE}
   [junit4]   2> 2799128 INFO  
(TEST-TestStreamBody.testQtUpdateFails-seed#[743B5868241FAF6]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testQtUpdateFails
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.request.TestStreamBody_743B5868241FAF6-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=en-GB, 
timezone=America/Ensenada
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=156714608,total=492359680
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestFuzzyAnalyzedSuggestions, DisMaxRequestHandlerTest, TestSort, 
TestSSLRandomization, TestFoldingMultitermQuery, TestIndexingPerformance, 
SolrInfoBeanTest, TestSolrDeletionPolicy2, TestQuerySenderNoQuery, 
PeerSyncTest, SimpleFacetsTest, TestConfigSets, TestAuthorizationFramework, 
TestReplicationHandlerBackup, TestJsonRequest, TestRandomDVFaceting, 
TestNamedUpdateProcessors, TestPHPSerializedResponseWriter, TestReloadDeadlock, 
TestJmxIntegration, TestMinMaxOnMultiValuedField, TestNumericTokenStream, 
TestCustomStream, TestDistributedSearch, TestConfigSetsAPIExclusivity, 
SparseHLLTest, SearchHandlerTest, TestSchemaNameResource, 
DirectSolrConnectionTest, ReplicaListTransformerTest, SuggesterTest, 
TestSolrCloudWithDelegationTokens, SuggesterWFSTTest, 
TestDistribDocBasedVersion, TestStressVersions, TestQuerySenderListener, 
DateFieldTest, TestCoreAdminApis, DistributedVersionInfoTest, 
ImplicitSnitchTest, TestStressUserVersions, FacetPivotSmallTest, 
TimeZoneUtilsTest, SubstringBytesRefFilterTest, TestLegacyFieldReuse, 
NodeMutatorTest, VersionInfoTest, HdfsChaosMonkeyNothingIsSafeTest, 
TestLFUCache, BufferStoreTest, TestDFRSimilarityFactory, TestSchemaManager, 
TestCollectionAPI, BigEndianAscendingWordSerializerTest, 
TestSQLHandlerNonCloud, DistributedExpandComponentTest, HdfsRecoverLeaseTest, 
ConnectionReuseTest, TestRandomFlRTGCloud, MultiTermTest, TestCloudInspectUtil, 
TestExtendedDismaxParser, SolrCoreMetricManagerTest, 
MissingSegmentRecoveryTest, TestStressInPlaceUpdates, BlockCacheTest, 
CoreMergeIndexesAdminHandlerTest, HighlighterConfigTest, TestCoreContainer, 
DistributedFacetPivotLargeTest, SimpleMLTQParserTest, TestRestoreCore, 
TestBlendedInfixSuggestions, ResponseHeaderTest, ForceLeaderTest, 
TestSerializedLuceneMatchVersion, TestRestManager, TestRebalanceLeaders, 
TestSolrConfigHandlerConcurrent, TestDFISimilarityFactory, 
DistanceFunctionTest, TestSweetSpotSimilarityFactory, ClusterStateTest, 
TestImplicitCoreProperties, HdfsCollectionsAPIDistributedZkTest, 
AddBlockUpdateTest, PrimitiveFieldTypeTest, TestCodecSupport, DocValuesTest, 
TestCloudNestedDocsSort, BooleanFieldTest, DateMathParserTest, 
SolrGangliaReporterTest, ScriptEngineTest, TriLevelCompositeIdRoutingTest, 
SpatialFilterTest, TestCollapseQParserPlugin, CdcrVersionReplicationTest, 
UninvertDocValuesMergePolicyTest, TestChildDocTransformer, TestCloudRecovery, 
TestNumericRangeQuery32, TestGraphMLResponseWriter, TestFieldCache, 
TestSolrCloudWithHadoopAuthPlugin, RequestHandlersTest, 
ChaosMonkeyNothingIsSafeTest, TestHashPartitioner, 
AsyncCallRequestStatusResponseTest, TestExportWriter, 
TestAuthenticationFramework, TestSortByMinMaxFunction, ConfigSetsAPITest, 
TestIBSimilarityFactory, HdfsThreadLeakTest, TestLRUStatsCache, 
CdcrUpdateLogTest, TestInitQParser, ResourceLoaderTest, 
TestFieldCacheVsDocValues, DistributedQueryComponentOptimizationTest, 
BasicDistributedZkTest, TestExpandComponent, 
PeerSyncWithIndexFingerprintCachingTest, CdcrBootstrapTest, StressHdfsTest, 
SpellCheckCollatorWithCollapseTest, TestExclusionRuleCollectionAccess, 
CdcrReplicationHandlerTest, TestOverriddenPrefixQueryForCustomFieldType, 
BasicAuthStandaloneTest, ReturnFieldsTest, DirectUpdateHandlerOptimizeTest, 
CSVRequestHandlerTest, TestLegacyFieldCache, HdfsRestartWhileUpdatingTest, 
TestExactSharedStatsCache, JvmMetricsTest, SecurityConfHandlerTest, 
TestGroupingSearch, SyncSliceTest, RegexBoostProcessorTest, 
TestScoreJoinQPNoScore, AtomicUpdateProcessorFactoryTest, 
HdfsDirectoryFactoryTest, TestTestInjection, PathHierarchyTokenizerFactoryTest, 
TestStressReorder, TestFieldTypeCollectionResource, TestMacroExpander, 
SolrCloudExampleTest, TestHighlightDedupGrouping, ExplicitHLLTest, 
TestConfigReload, TestBinaryField, XsltUpdateRequestHandlerTest, 
TestMultiWordSynonyms, TestManagedSynonymGraphFilterFactory, 
TestInPlaceUpdatesStandalone, OverseerTest, IndexBasedSpellCheckerTest, 
ZkControllerTest, SolrMetricReporterTest, DistribCursorPagingTest, 
TestXmlQParser, CursorMarkTest, TestSolr4Spatial2, TestPseudoReturnFields, 
DirectUpdateHandlerTest, AutoScalingHandlerTest, TestManagedSchema, 
OutputWriterTest, RequiredFieldsTest, AutoCommitTest, TestFaceting, 
DistributedSpellCheckComponentTest, HardAutoCommitTest, SolrCmdDistributorTest, 
TestFiltering, TestFunctionQuery, TestLazyCores, 
DistributedQueryElevationComponentTest, SolrIndexSplitterTest, 
SimplePostToolTest, AnalysisAfterCoreReloadTest, SuggesterFSTTest, 
CoreAdminHandlerTest, PolyFieldTest, NoCacheHeaderTest, 
WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, 
SolrCoreCheckLockOnStartupTest, TestUpdate, TestAtomicUpdateErrorCases, 
QueryEqualityTest, DefaultValueUpdateProcessorTest, 
StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, 
TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1, 
DebugComponentTest, TestSurroundQueryParser, LukeRequestHandlerTest, 
TestWriterPerf, TestReversedWildcardFilterFactory, TestOmitPositions, 
TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, 
TermVectorComponentTest, MoreLikeThisHandlerTest, 
FieldAnalysisRequestHandlerTest, TestSolrQueryParser, 
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, 
SolrPluginUtilsTest, TestCollationField, QueryParsingTest, 
UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, 
SOLR749Test, AlternateDirectoryTest, ResponseLogComponentTest, 
BadComponentTest, TestStressRecovery, CleanupOldIndexTest, 
CollectionTooManyReplicasTest, CreateCollectionCleanupTest, 
CustomCollectionTest, DeleteShardTest, DeleteStatusTest, 
DistribJoinFromCollectionTest, DistributedQueueTest, HttpPartitionTest, 
MoveReplicaHDFSTest, MoveReplicaTest, PeerSyncReplicationTest, 
RemoteQueryErrorTest, ReplicationFactorTest, RollingRestartTest, 
SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, 
SolrXmlInZkTest, TestCloudPivotFacet, TestCloudPseudoReturnFields, 
TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDeleteCollectionOnDownNodes, 
TestLeaderElectionWithEmptyReplica, TestLeaderElectionZkExpiry, 
TestLeaderInitiatedRecoveryThread, TestLocalFSCloudBackupRestore, TestLockTree, 
TestMiniSolrCloudCluster, TestMiniSolrCloudClusterSSL, 
TestOnReconnectListenerSupport, TestRandomRequestDistribution, 
TestReplicaProperties, TestRequestForwarding, TestRequestStatusCollectionAPI, 
TestSolrCloudWithKerberosAlt, TestTlogReplica, TestSystemCollAutoCreate, 
V2ApiIntegrationTest, InfoHandlerTest, MetricsHandlerTest, TestApiFramework, 
TestCollectionAPIs, DistributedFacetExistsSmallTest, 
DistributedFacetPivotWhiteBoxTest, SuggestComponentContextFilterQueryTest, 
SuggestComponentTest, TestDistributedStatsComponentCardinality, 
JavabinLoaderTest, HighlighterMaxOffsetTest, TestSlowCompositeReaderWrapper, 
CheckHdfsIndexTest, TestLegacyField, TestLegacyNumericUtils, MetricsConfigTest, 
SolrJmxReporterTest, TestIntervalFaceting, TestStreamBody]
   [junit4] Completed [618/727 (4!)] on J0 in 2.49s, 2 tests, 1 failure <<< 
FAILURES!

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

Reply via email to