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