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

6 tests failed.
FAILED:  org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForHashRouter

Error Message:
Collection not found: routeFieldColl

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


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

Error Message:
Collection not found: withShardField

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


FAILED:  org.apache.solr.ltr.TestLTRQParserPlugin.ltrMoreResultsThanReRankedTest

Error Message:
mismatch: '0.09271725'!='0.105360515' @ response/docs/[3]/score

Stack Trace:
java.lang.RuntimeException: mismatch: '0.09271725'!='0.105360515' @ 
response/docs/[3]/score
        at 
__randomizedtesting.SeedInfo.seed([C26AFDEF8D7736D6:B124C8852B86117]:0)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192)
        at 
org.apache.solr.ltr.TestLTRQParserPlugin.ltrMoreResultsThanReRankedTest(TestLTRQParserPlugin.java:94)
        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.ltr.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder

Error Message:
mismatch: '3'!='4' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([C26AFDEF8D7736D6:A726DD8F4715513E]:0)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192)
        at 
org.apache.solr.ltr.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder(TestParallelWeightCreation.java:45)
        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.ltr.TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore

Error Message:
mismatch: '3'!='4' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: '3'!='4' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([C26AFDEF8D7736D6:9F5BEA2A004B41BD]:0)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192)
        at 
org.apache.solr.ltr.TestSelectiveWeightCreation.testSelectiveWeightsRequestFeaturesFromDifferentStore(TestSelectiveWeightCreation.java:230)
        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.ltr.feature.TestFieldLengthFeature.testRanking

Error Message:
mismatch: '8'!='1' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: '8'!='1' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([C26AFDEF8D7736D6:5D5C5471150D2919]:0)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248)
        at org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192)
        at 
org.apache.solr.ltr.feature.TestFieldLengthFeature.testRanking(TestFieldLengthFeature.java:117)
        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 12550 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/init-core-data-001
   [junit4]   2> 1680546 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1680549 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-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> 1680550 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001
   [junit4]   2> 1680550 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1680551 INFO  (Thread-3414) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1680551 INFO  (Thread-3414) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1680553 ERROR (Thread-3414) [    ] 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> 1680659 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:50557
   [junit4]   2> 1680685 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1680685 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1680685 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1680685 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1680686 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@620de201{/solr,null,AVAILABLE}
   [junit4]   2> 1680687 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5c422a7c{HTTP/1.1,[http/1.1]}{127.0.0.1:50559}
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.e.j.s.Server Started @1684576ms
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50559}
   [junit4]   2> 1680688 ERROR (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1680688 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-21T07:43:56.504Z
   [junit4]   2> 1680689 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7e386a22{/solr,null,AVAILABLE}
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@66276a8{/solr,null,AVAILABLE}
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6b865a04{HTTP/1.1,[http/1.1]}{127.0.0.1:50562}
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.e.j.s.Server Started @1684579ms
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50562}
   [junit4]   2> 1680690 ERROR (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@20572085{/solr,null,AVAILABLE}
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1680690 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-21T07:43:56.507Z
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@22f8e4bb{HTTP/1.1,[http/1.1]}{127.0.0.1:50561}
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.e.j.s.Server Started @1684580ms
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50561}
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@40b7c5a3{HTTP/1.1,[http/1.1]}{127.0.0.1:50563}
   [junit4]   2> 1680691 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.e.j.s.Server Started @1684580ms
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50563}
   [junit4]   2> 1680692 ERROR (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1680692 ERROR (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1680693 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1680693 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-21T07:43:56.509Z
   [junit4]   2> 1680692 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-21T07:43:56.508Z
   [junit4]   2> 1680693 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1680695 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1680696 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1680696 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1680714 INFO  (jetty-launcher-2684-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50557/solr
   [junit4]   2> 1680714 INFO  (jetty-launcher-2684-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50557/solr
   [junit4]   2> 1680715 INFO  (jetty-launcher-2684-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50557/solr
   [junit4]   2> 1680716 INFO  (jetty-launcher-2684-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50557/solr
   [junit4]   2> 1680768 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1680768 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1680769 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1680769 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1680770 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:50559_solr
   [junit4]   2> 1680772 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50563_solr
   [junit4]   2> 1680772 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50562_solr
   [junit4]   2> 1680772 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50561_solr
   [junit4]   2> 1680773 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.Overseer Overseer 
(id=97999430377013257-127.0.0.1:50559_solr-n_0000000000) starting
   [junit4]   2> 1680775 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1680775 INFO  
(zkCallback-2703-thread-1-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1680781 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1680785 INFO  
(zkCallback-2705-thread-1-processing-n:127.0.0.1:50563_solr) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1680852 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50559_solr
   [junit4]   2> 1680856 INFO  
(zkCallback-2703-thread-1-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1680857 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1680857 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1680862 INFO  
(zkCallback-2705-thread-1-processing-n:127.0.0.1:50563_solr) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1680900 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50563.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680905 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50563.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680905 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50563.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680907 INFO  (jetty-launcher-2684-thread-4) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node4/.
   [junit4]   2> 1680911 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50562.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680915 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50562.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680915 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50562.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680916 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50561.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680916 INFO  (jetty-launcher-2684-thread-3) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node3/.
   [junit4]   2> 1680918 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50561.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680918 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50561.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1680919 INFO  (jetty-launcher-2684-thread-2) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node2/.
   [junit4]   2> 1681025 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50559.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1681028 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50559.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1681028 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50559.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1681029 INFO  (jetty-launcher-2684-thread-1) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node1/.
   [junit4]   2> 1681062 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1681064 INFO  
(SUITE-CustomCollectionTest-seed#[6959D7BB13DFF80]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50557/solr ready
   [junit4]   2> 1681086 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[6959D7BB13DFF80]) [   
 ] o.a.s.SolrTestCaseJ4 ###Starting testCreateShardRepFactor
   [junit4]   2> 1681088 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[6959D7BB13DFF80]) [   
 ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1681092 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1681095 INFO  
(OverseerThreadFactory-7966-thread-1-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
testCreateShardRepFactor
   [junit4]   2> 1681215 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2
   [junit4]   2> 1681215 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2
   [junit4]   2> 1681215 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1681215 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1681325 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1681325 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1682244 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1682244 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1682251 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_a_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 1682251 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_b_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 1682283 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1682283 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_b_replica1' 
using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1682283 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1682283 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_a_replica1' 
using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1682284 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50562.solr.core.testCreateShardRepFactor.b.replica1' (registry 
'solr.core.testCreateShardRepFactor.b.replica1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1682284 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50561.solr.core.testCreateShardRepFactor.a.replica1' (registry 
'solr.core.testCreateShardRepFactor.a.replica1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1682284 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1682284 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1682284 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_b_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node3/testCreateShardRepFactor_b_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node3/./testCreateShardRepFactor_b_replica1/data/]
   [junit4]   2> 1682284 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_a_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node2/testCreateShardRepFactor_a_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node2/./testCreateShardRepFactor_a_replica1/data/]
   [junit4]   2> 1682369 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1682369 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1682369 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1682369 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1682371 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1682371 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1682371 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1682371 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1682373 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2874ef9d[testCreateShardRepFactor_b_replica1] main]
   [junit4]   2> 1682373 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@46f967c5[testCreateShardRepFactor_a_replica1] main]
   [junit4]   2> 1682374 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1682374 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1682375 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1682375 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1682375 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1682375 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1682375 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1567990887943766016
   [junit4]   2> 1682375 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1567990887943766016
   [junit4]   2> 1682376 INFO  
(searcherExecutor-7975-thread-1-processing-n:127.0.0.1:50561_solr 
x:testCreateShardRepFactor_a_replica1 s:a c:testCreateShardRepFactor) 
[n:127.0.0.1:50561_solr c:testCreateShardRepFactor s:a  
x:testCreateShardRepFactor_a_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_a_replica1] Registered new searcher 
Searcher@46f967c5[testCreateShardRepFactor_a_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1682377 INFO  
(searcherExecutor-7974-thread-1-processing-n:127.0.0.1:50562_solr 
x:testCreateShardRepFactor_b_replica1 s:b c:testCreateShardRepFactor) 
[n:127.0.0.1:50562_solr c:testCreateShardRepFactor s:b  
x:testCreateShardRepFactor_b_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_b_replica1] Registered new searcher 
Searcher@2874ef9d[testCreateShardRepFactor_b_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:50562/solr/testCreateShardRepFactor_b_replica1/
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:50562/solr/testCreateShardRepFactor_b_replica1/ has no replicas
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1682383 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:50561/solr/testCreateShardRepFactor_a_replica1/
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:50561/solr/testCreateShardRepFactor_a_replica1/ has no replicas
   [junit4]   2> 1682383 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1682389 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:50562/solr/testCreateShardRepFactor_b_replica1/ b
   [junit4]   2> 1682389 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:50561/solr/testCreateShardRepFactor_a_replica1/ a
   [junit4]   2> 1682504 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1682504 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1682550 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1682550 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1682553 INFO  (qtp1520250893-15685) [n:127.0.0.1:50561_solr 
c:testCreateShardRepFactor s:a  x:testCreateShardRepFactor_a_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_a_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=a&wt=javabin&version=2}
 status=0 QTime=1338
   [junit4]   2> 1682553 INFO  (qtp223770966-15680) [n:127.0.0.1:50562_solr 
c:testCreateShardRepFactor s:b  x:testCreateShardRepFactor_b_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=testCreateShardRepFactor_b_replica1&action=CREATE&numShards=2&collection=testCreateShardRepFactor&shard=b&wt=javabin&version=2}
 status=0 QTime=1338
   [junit4]   2> 1682558 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_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> 1682669 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1682669 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1683107 INFO  
(OverseerCollectionConfigSetProcessor-97999430377013257-127.0.0.1:50559_solr-n_0000000000)
 [n:127.0.0.1:50559_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> 1683568 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={shards=a,b&replicationFactor=1&collection.configName=conf&name=testCreateShardRepFactor&router.name=implicit&action=CREATE&wt=javabin&version=2}
 status=0 QTime=2476
   [junit4]   2> 1683568 INFO  (qtp1504617058-15698) [n:127.0.0.1:50563_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> 1683572 INFO  
(OverseerThreadFactory-7966-thread-2-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateShardCmd Create shard invoked: {
   [junit4]   2>   "collection":"testCreateShardRepFactor",
   [junit4]   2>   "shard":"x",
   [junit4]   2>   "operation":"createshard"}
   [junit4]   2> 1683686 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1683686 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1683797 INFO  
(OverseerThreadFactory-7966-thread-2-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateShardCmd Creating shard 
testCreateShardRepFactor_x_replica1 as part of slice x of collection 
testCreateShardRepFactor on 127.0.0.1:50563_solr
   [junit4]   2> 1683798 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&name=testCreateShardRepFactor_x_replica1&action=CREATE&numShards=1&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2
   [junit4]   2> 1683798 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1683908 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1683908 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1683908 INFO  
(zkCallback-2705-thread-1-processing-n:127.0.0.1:50563_solr) 
[n:127.0.0.1:50563_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> 1684815 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1684820 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.IndexSchema [testCreateShardRepFactor_x_replica1] Schema 
name=cloud-dynamic
   [junit4]   2> 1684854 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1684854 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'testCreateShardRepFactor_x_replica1' 
using configuration from collection testCreateShardRepFactor, trusted=true
   [junit4]   2> 1684854 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50563.solr.core.testCreateShardRepFactor.x.replica1' (registry 
'solr.core.testCreateShardRepFactor.x.replica1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1684854 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1684854 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SolrCore [[testCreateShardRepFactor_x_replica1] ] Opening new SolrCore 
at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node4/testCreateShardRepFactor_x_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node4/./testCreateShardRepFactor_x_replica1/data/]
   [junit4]   2> 1684941 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1684942 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1684944 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1684944 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1684945 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@35303a57[testCreateShardRepFactor_x_replica1] main]
   [junit4]   2> 1684947 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1684948 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1684948 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1684949 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1567990890642800640
   [junit4]   2> 1684949 INFO  
(searcherExecutor-7984-thread-1-processing-n:127.0.0.1:50563_solr 
x:testCreateShardRepFactor_x_replica1 s:x c:testCreateShardRepFactor) 
[n:127.0.0.1:50563_solr c:testCreateShardRepFactor s:x  
x:testCreateShardRepFactor_x_replica1] o.a.s.c.SolrCore 
[testCreateShardRepFactor_x_replica1] Registered new searcher 
Searcher@35303a57[testCreateShardRepFactor_x_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1684957 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1684957 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1684958 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:50563/solr/testCreateShardRepFactor_x_replica1/
   [junit4]   2> 1684958 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1684958 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:50563/solr/testCreateShardRepFactor_x_replica1/ has no replicas
   [junit4]   2> 1684958 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1684963 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:50563/solr/testCreateShardRepFactor_x_replica1/ x
   [junit4]   2> 1685078 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_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> 1685078 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_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> 1685079 INFO  
(zkCallback-2705-thread-1-processing-n:127.0.0.1:50563_solr) 
[n:127.0.0.1:50563_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> 1685124 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1685127 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:testCreateShardRepFactor s:x  x:testCreateShardRepFactor_x_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&name=testCreateShardRepFactor_x_replica1&action=CREATE&numShards=1&collection=testCreateShardRepFactor&shard=x&wt=javabin&version=2}
 status=0 QTime=1329
   [junit4]   2> 1685127 INFO  
(OverseerThreadFactory-7966-thread-2-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateShardCmd Finished create command on 
all shards for collection: testCreateShardRepFactor
   [junit4]   2> 1685130 INFO  (qtp1504617058-15698) [n:127.0.0.1:50563_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=1561
   [junit4]   2> 1685132 INFO  
(TEST-CustomCollectionTest.testCreateShardRepFactor-seed#[6959D7BB13DFF80]) [   
 ] o.a.s.SolrTestCaseJ4 ###Ending testCreateShardRepFactor
   [junit4]   2> 1685155 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[6959D7BB13DFF80]) [   
 ] o.a.s.SolrTestCaseJ4 ###Starting testCustomCollectionsAPI
   [junit4]   2> 1685157 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[6959D7BB13DFF80]) [   
 ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1685159 INFO  (qtp1504617058-15705) [n:127.0.0.1:50563_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> 1685162 INFO  
(OverseerCollectionConfigSetProcessor-97999430377013257-127.0.0.1:50559_solr-n_0000000000)
 [n:127.0.0.1:50559_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> 1685163 INFO  
(OverseerThreadFactory-7966-thread-3-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 1685164 INFO  (qtp223770966-15682) [n:127.0.0.1:50562_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.b.replica1, tag=null
   [junit4]   2> 1685164 INFO  (qtp1520250893-15686) [n:127.0.0.1:50561_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.a.replica1, tag=null
   [junit4]   2> 1685165 INFO  (qtp1504617058-15702) [n:127.0.0.1:50563_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.x.replica1, tag=null
   [junit4]   2> 1685216 INFO  (qtp1520250893-15686) [n:127.0.0.1:50561_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_a_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5a9f8adc
   [junit4]   2> 1685216 INFO  (qtp223770966-15682) [n:127.0.0.1:50562_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_b_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@44c0283a
   [junit4]   2> 1685217 INFO  (qtp1504617058-15702) [n:127.0.0.1:50563_solr    
] o.a.s.c.SolrCore [testCreateShardRepFactor_x_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7b8f6b41
   [junit4]   2> 1685218 INFO  (qtp1520250893-15686) [n:127.0.0.1:50561_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.a.replica1, tag=1520405212
   [junit4]   2> 1685218 INFO  (qtp1520250893-15686) [n:127.0.0.1:50561_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.a.leader, tag=1520405212
   [junit4]   2> 1685218 INFO  (qtp223770966-15682) [n:127.0.0.1:50562_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.b.replica1, tag=1153443898
   [junit4]   2> 1685218 INFO  (qtp223770966-15682) [n:127.0.0.1:50562_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.b.leader, tag=1153443898
   [junit4]   2> 1685219 INFO  (qtp1504617058-15702) [n:127.0.0.1:50563_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.testCreateShardRepFactor.x.replica1, tag=2072996673
   [junit4]   2> 1685219 INFO  (qtp1504617058-15702) [n:127.0.0.1:50563_solr    
] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testCreateShardRepFactor.x.leader, tag=2072996673
   [junit4]   2> 1685221 INFO  (qtp1520250893-15686) [n:127.0.0.1:50561_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_a_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=57
   [junit4]   2> 1685222 INFO  (qtp223770966-15682) [n:127.0.0.1:50562_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_b_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=58
   [junit4]   2> 1685222 INFO  (qtp1504617058-15702) [n:127.0.0.1:50563_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=testCreateShardRepFactor_x_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=57
   [junit4]   2> 1685941 INFO  (qtp1504617058-15705) [n:127.0.0.1:50563_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=testCreateShardRepFactor&action=DELETE&wt=javabin&version=2} 
status=0 QTime=782
   [junit4]   2> 1685942 INFO  (qtp1504617058-15683) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b,c&replicationFactor=5&collection.configName=conf&maxShardsPerNode=6&name=implicitcoll&router.name=implicit&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1685946 INFO  
(OverseerThreadFactory-7966-thread-4-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
implicitcoll
   [junit4]   2> 1685946 WARN  
(OverseerThreadFactory-7966-thread-4-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.CreateCollectionCmd Specified 
replicationFactor of 5 on collection implicitcoll is higher than or equal to 
the number of Solr instances currently live or live and part of your 
createNodeSet(4). It's unusual to run two replica of the same slice on the same 
Solr-instance.
   [junit4]   2> 1685947 INFO  
(OverseerCollectionConfigSetProcessor-97999430377013257-127.0.0.1:50559_solr-n_0000000000)
 [n:127.0.0.1:50559_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> 1686176 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1686177 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1686178 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1686187 INFO  (qtp791975457-15669) [n:127.0.0.1:50559_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1686187 INFO  (qtp223770966-15688) [n:127.0.0.1:50562_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1686188 INFO  (qtp791975457-15669) [n:127.0.0.1:50559_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1686188 INFO  (qtp1520250893-15687) [n:127.0.0.1:50561_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1686188 INFO  (qtp223770966-15692) [n:127.0.0.1:50562_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1686189 INFO  (qtp791975457-15671) [n:127.0.0.1:50559_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1686189 INFO  (qtp1520250893-15689) [n:127.0.0.1:50561_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1686190 INFO  (qtp223770966-15693) [n:127.0.0.1:50562_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1686190 INFO  (qtp791975457-15672) [n:127.0.0.1:50559_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 1686190 INFO  (qtp1520250893-15690) [n:127.0.0.1:50561_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 1686190 INFO  (qtp223770966-15694) [n:127.0.0.1:50562_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1686191 INFO  (qtp1520250893-15691) [n:127.0.0.1:50561_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica5&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1686193 INFO  (qtp791975457-15673) [n:127.0.0.1:50559_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 1686330 INFO  
(zkCallback-2705-thread-1-processing-n:127.0.0.1:50563_solr) 
[n:127.0.0.1:50563_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 1686330 INFO  
(zkCallback-2706-thread-1-processing-n:127.0.0.1:50562_solr) 
[n:127.0.0.1:50562_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 1686330 INFO  
(zkCallback-2704-thread-1-processing-n:127.0.0.1:50561_solr) 
[n:127.0.0.1:50561_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 1686330 INFO  
(zkCallback-2703-thread-1-processing-n:127.0.0.1:50559_solr) 
[n:127.0.0.1:50559_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 1687209 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687209 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687253 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema 
[implicitcoll_a_replica4] Schema name=cloud-dynamic
   [junit4]   2> 1687263 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema 
[implicitcoll_c_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1687295 INFO  (qtp1504617058-15701) [n:127.0.0.1:50563_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687309 INFO  (qtp223770966-15688) [n:127.0.0.1:50562_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica5] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687309 INFO  (qtp223770966-15693) [n:127.0.0.1:50562_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687309 INFO  (qtp223770966-15692) [n:127.0.0.1:50562_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 7.0.0
   [junit4]   2> 1687323 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1687323 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_c_replica2' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 1687323 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1687323 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_a_replica4' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 1687324 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_50563.solr.core.implicitcoll.a.replica4' (registry 
'solr.core.implicitcoll.a.replica4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1687324 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_50563.solr.core.implicitcoll.c.replica2' (registry 
'solr.core.implicitcoll.c.replica2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@11e7ac4b
   [junit4]   2> 1687324 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1687324 INFO  (qtp1504617058-15700) [n:127.0.0.1:50563_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore 
[[implicitcoll_a_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node4/implicitcoll_a_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CustomCollectionTest_6959D7BB13DFF80-001/tempDir-001/node4/./implicitcoll_a_replica4/data/]
   [junit4]   2> 1687324 INFO  (qtp1504617058-15696) [n:127.0.0.1:50563_solr 
c:implicitcoll s:c

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

.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.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> 36266 INFO  (qtp1381385498-321) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/query 
params={q=*:*&indent=on&fl=*,score&rows=4&wt=json&rq={!ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3}}
 hits=5 status=0 QTime=4
   [junit4]   2> 36267 ERROR 
(TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[C26AFDEF8D7736D6])
 [    ] o.a.s.u.RestTestBase query failed JSON validation. error=mismatch: 
'3'!='4' @ response/docs/[0]/id
   [junit4]   2>  expected =/response/docs/[0]/id=='3'
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":4,
   [junit4]   2>     "params":{
   [junit4]   2>       "q":"*:*",
   [junit4]   2>       "indent":"on",
   [junit4]   2>       "fl":"*,score",
   [junit4]   2>       "rows":"4",
   [junit4]   2>       "wt":"json",
   [junit4]   2>       "rq":"{!ltr reRankDocs=10 model=externalmodel 
efi.user_query=w3}"}},
   [junit4]   2>   
"response":{"numFound":5,"start":0,"maxScore":0.36707595,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"4",
   [junit4]   2>         "title":"w3 w3",
   [junit4]   2>         "description":"w4",
   [junit4]   2>         "popularity":4,
   [junit4]   2>         "_version_":1567992511591350272,
   [junit4]   2>         "score":0.36707595},
   [junit4]   2>       {
   [junit4]   2>         "id":"3",
   [junit4]   2>         "title":"w3",
   [junit4]   2>         "description":"w3",
   [junit4]   2>         "popularity":3,
   [junit4]   2>         "_version_":1567992511589253120,
   [junit4]   2>         "score":0.33873552},
   [junit4]   2>       {
   [junit4]   2>         "id":"1",
   [junit4]   2>         "title":"w1 w3",
   [junit4]   2>         "description":"w1",
   [junit4]   2>         "popularity":1,
   [junit4]   2>         "_version_":1567992511557795840,
   [junit4]   2>         "score":0.2545195},
   [junit4]   2>       {
   [junit4]   2>         "id":"2",
   [junit4]   2>         "title":"w2",
   [junit4]   2>         "description":"w2",
   [junit4]   2>         "popularity":2,
   [junit4]   2>         "_version_":1567992511586107392,
   [junit4]   2>         "score":0.0}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = 
/query?q=*%3A*&fl=*%2Cscore&rows=4&rq=%7B%21ltr+reRankDocs%3D10+model%3Dexternalmodel+efi.user_query%3Dw3%7D&wt=json&indent=on
   [junit4]   2> 
   [junit4]   2> 36268 INFO  
(TEST-TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder-seed#[C26AFDEF8D7736D6])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testLTRScoringQueryParallelWeightCreationResultOrder
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestParallelWeightCreation 
-Dtests.method=testLTRScoringQueryParallelWeightCreationResultOrder 
-Dtests.seed=C26AFDEF8D7736D6 -Dtests.slow=true -Dtests.locale=ru-RU 
-Dtests.timezone=Africa/Casablanca -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   1.62s J0 | 
TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder 
<<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: '3'!='4' @ 
response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([C26AFDEF8D7736D6:A726DD8F4715513E]:0)
   [junit4]    >        at 
org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:248)
   [junit4]    >        at 
org.apache.solr.util.RestTestBase.assertJQ(RestTestBase.java:192)
   [junit4]    >        at 
org.apache.solr.ltr.TestParallelWeightCreation.testLTRScoringQueryParallelWeightCreationResultOrder(TestParallelWeightCreation.java:45)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@5eeb0b0d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2033175406
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 36270 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 36277 INFO  (coreCloseExecutor-276-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@22508250
   [junit4]   2> 36279 INFO  (coreCloseExecutor-276-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=575701584
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6ce5ce65{/solr,null,UNAVAILABLE}
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=282345375
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 36280 INFO  
(SUITE-TestParallelWeightCreation-seed#[C26AFDEF8D7736D6]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 36285 INFO  (coreCloseExecutor-278-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6a2bd0f
   [junit4]   2> 36287 INFO  (coreCloseExecutor-278-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=111328527
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.TestParallelWeightCreation_C26AFDEF8D7736D6-001
   [junit4]   2> May 21, 2017 8:09:46 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{_version_=PostingsFormat(name=LuceneFixedGap), 
popularity=PostingsFormat(name=Memory), description=Lucene50(blocksize=128), 
id=FSTOrd50, text=PostingsFormat(name=Memory), 
title=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, 
maxPointsInLeafNode=1742, maxMBSortInHeap=5.988717647882187, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ru-RU, 
timezone=Africa/Casablanca
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=41895264,total=99753984
   [junit4]   2> NOTE: All tests run in this JVM: [TestModelManagerPersistence, 
TestLTRScoringQuery, TestEdisMaxSolrFeature, TestRerankBase, TestLTRWithSort, 
TestExternalValueFeatures, TestUserTermScorereQDF, TestLinearModel, 
TestFieldValueFeature, TestLTRQParserExplain, TestLTRQParserPlugin, 
TestLTRReRankingPipeline, TestParallelWeightCreation]
   [junit4] Completed [21/33 (4!)] on J0 in 11.69s, 2 tests, 1 error <<< 
FAILURES!

[...truncated 1108 lines...]
-validate-source-patterns:
[source-patterns] tabs instead spaces: solr/solr-ref-guide/src/css/ref-guide.css
[source-patterns] tabs instead spaces: 
solr/solr-ref-guide/src/js/ref-guide-toc.js

BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:775: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:130: Found 2 
violations in source files (tabs instead spaces).

Total time: 73 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to