Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1327/
4 tests failed. FAILED: org.apache.solr.handler.extraction.TestExtractionDateUtil.testParseDate Error Message: Incorrect parsed timestamp: 1226583351000 != 1226579751000 (Thu Nov 13 04:35:51 AKST 2008) Stack Trace: java.lang.AssertionError: Incorrect parsed timestamp: 1226583351000 != 1226579751000 (Thu Nov 13 04:35:51 AKST 2008) at __randomizedtesting.SeedInfo.seed([1405E46F66B64616:5E1C9C5A1D1F31A3]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.handler.extraction.TestExtractionDateUtil.assertParsedDate(TestExtractionDateUtil.java:59) at org.apache.solr.handler.extraction.TestExtractionDateUtil.testParseDate(TestExtractionDateUtil.java:54) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at org.apache.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 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.CollectionsAPISolrJTest.testCreateAndDeleteShard Error Message: Error from server at https://127.0.0.1:34108/solr: deleteshard the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:34108/solr: deleteshard the collection time out:180s at __randomizedtesting.SeedInfo.seed([B693392A06F62FF5:73CD8D368003E508]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:595) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:231) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:220) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:459) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:389) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1135) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:876) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195) at org.apache.solr.cloud.CollectionsAPISolrJTest.testCreateAndDeleteShard(CollectionsAPISolrJTest.java:143) 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.handler.TestReplicationHandler.doTestReplicateAfterCoreReload Error Message: expected:<[{indexVersion=1497614187568,generation=2,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jvx.fdt, _jvx.fdx, _jvx.fnm, _jvx.nvd, _jvx.nvm, _jvx.si, _jvx_Lucene50_0.doc, _jvx_Lucene50_0.tim, _jvx_Lucene50_0.tip, _jwr.cfe, _jwr.cfs, _jwr.si, _jws.fdt, _jws.fdx, _jws.fnm, _jws.nvd, _jws.nvm, _jws.si, _jws_Lucene50_0.doc, _jws_Lucene50_0.tim, _jws_Lucene50_0.tip, _jwt.cfe, _jwt.cfs, _jwt.si, _jwu.cfe, _jwu.cfs, _jwu.si, _jwv.cfe, _jwv.cfs, _jwv.si, _jww.cfe, _jww.cfs, _jww.si, _jwx.cfe, _jwx.cfs, _jwx.si, _jwy.cfe, _jwy.cfs, _jwy.si, _jwz.cfe, _jwz.cfs, _jwz.si, _jx0.cfe, _jx0.cfs, _jx0.si, _jx1.cfe, _jx1.cfs, _jx1.si, _jx2.cfe, _jx2.cfs, _jx2.si, _jx3.cfe, _jx3.cfs, _jx3.si, _jx4.cfe, _jx4.cfs, _jx4.si, _jx5.cfe, _jx5.cfs, _jx5.si, _jx6.cfe, _jx6.cfs, _jx6.si, _jx7.cfe, _jx7.cfs, _jx7.si, _jx8.cfe, _jx8.cfs, _jx8.si, _jx9.cfe, _jx9.cfs, _jx9.si, _jxa.cfe, _jxa.cfs, _jxa.si, _jxb.cfe, _jxb.cfs, _jxb.si, _jxc.cfe, _jxc.cfs, _jxc.si, _jxd.cfe, _jxd.cfs, _jxd.si, _jxe.cfe, _jxe.cfs, _jxe.si, _jxf.cfe, _jxf.cfs, _jxf.si, _jxg.cfe, _jxg.cfs, _jxg.si, _jxh.cfe, _jxh.cfs, _jxh.si, _jxi.cfe, _jxi.cfs, _jxi.si, _jxj.cfe, _jxj.cfs, _jxj.si, _jxk.cfe, _jxk.cfs, _jxk.si, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_2]}]> but was:<[{indexVersion=1497614187568,generation=2,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jvx.fdt, _jvx.fdx, _jvx.fnm, _jvx.nvd, _jvx.nvm, _jvx.si, _jvx_Lucene50_0.doc, _jvx_Lucene50_0.tim, _jvx_Lucene50_0.tip, _jwr.cfe, _jwr.cfs, _jwr.si, _jws.fdt, _jws.fdx, _jws.fnm, _jws.nvd, _jws.nvm, _jws.si, _jws_Lucene50_0.doc, _jws_Lucene50_0.tim, _jws_Lucene50_0.tip, _jwt.cfe, _jwt.cfs, _jwt.si, _jwu.cfe, _jwu.cfs, _jwu.si, _jwv.cfe, _jwv.cfs, _jwv.si, _jww.cfe, _jww.cfs, _jww.si, _jwx.cfe, _jwx.cfs, _jwx.si, _jwy.cfe, _jwy.cfs, _jwy.si, _jwz.cfe, _jwz.cfs, _jwz.si, _jx0.cfe, _jx0.cfs, _jx0.si, _jx1.cfe, _jx1.cfs, _jx1.si, _jx2.cfe, _jx2.cfs, _jx2.si, _jx3.cfe, _jx3.cfs, _jx3.si, _jx4.cfe, _jx4.cfs, _jx4.si, _jx5.cfe, _jx5.cfs, _jx5.si, _jx6.cfe, _jx6.cfs, _jx6.si, _jx7.cfe, _jx7.cfs, _jx7.si, _jx8.cfe, _jx8.cfs, _jx8.si, _jx9.cfe, _jx9.cfs, _jx9.si, _jxa.cfe, _jxa.cfs, _jxa.si, _jxb.cfe, _jxb.cfs, _jxb.si, _jxc.cfe, _jxc.cfs, _jxc.si, _jxd.cfe, _jxd.cfs, _jxd.si, _jxe.cfe, _jxe.cfs, _jxe.si, _jxf.cfe, _jxf.cfs, _jxf.si, _jxg.cfe, _jxg.cfs, _jxg.si, _jxh.cfe, _jxh.cfs, _jxh.si, _jxi.cfe, _jxi.cfs, _jxi.si, _jxj.cfe, _jxj.cfs, _jxj.si, _jxk.cfe, _jxk.cfs, _jxk.si, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_2]}, {indexVersion=1497614187568,generation=3,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jxk.cfe, _jxk.cfs, _jxk.si, _jxl.fdt, _jxl.fdx, _jxl.fnm, _jxl.nvd, _jxl.nvm, _jxl.si, _jxl_Lucene50_0.doc, _jxl_Lucene50_0.tim, _jxl_Lucene50_0.tip, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_3]}]> Stack Trace: java.lang.AssertionError: expected:<[{indexVersion=1497614187568,generation=2,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jvx.fdt, _jvx.fdx, _jvx.fnm, _jvx.nvd, _jvx.nvm, _jvx.si, _jvx_Lucene50_0.doc, _jvx_Lucene50_0.tim, _jvx_Lucene50_0.tip, _jwr.cfe, _jwr.cfs, _jwr.si, _jws.fdt, _jws.fdx, _jws.fnm, _jws.nvd, _jws.nvm, _jws.si, _jws_Lucene50_0.doc, _jws_Lucene50_0.tim, _jws_Lucene50_0.tip, _jwt.cfe, _jwt.cfs, _jwt.si, _jwu.cfe, _jwu.cfs, _jwu.si, _jwv.cfe, _jwv.cfs, _jwv.si, _jww.cfe, _jww.cfs, _jww.si, _jwx.cfe, _jwx.cfs, _jwx.si, _jwy.cfe, _jwy.cfs, _jwy.si, _jwz.cfe, _jwz.cfs, _jwz.si, _jx0.cfe, _jx0.cfs, _jx0.si, _jx1.cfe, _jx1.cfs, _jx1.si, _jx2.cfe, _jx2.cfs, _jx2.si, _jx3.cfe, _jx3.cfs, _jx3.si, _jx4.cfe, _jx4.cfs, _jx4.si, _jx5.cfe, _jx5.cfs, _jx5.si, _jx6.cfe, _jx6.cfs, _jx6.si, _jx7.cfe, _jx7.cfs, _jx7.si, _jx8.cfe, _jx8.cfs, _jx8.si, _jx9.cfe, _jx9.cfs, _jx9.si, _jxa.cfe, _jxa.cfs, _jxa.si, _jxb.cfe, _jxb.cfs, _jxb.si, _jxc.cfe, _jxc.cfs, _jxc.si, _jxd.cfe, _jxd.cfs, _jxd.si, _jxe.cfe, _jxe.cfs, _jxe.si, _jxf.cfe, _jxf.cfs, _jxf.si, _jxg.cfe, _jxg.cfs, _jxg.si, _jxh.cfe, _jxh.cfs, _jxh.si, _jxi.cfe, _jxi.cfs, _jxi.si, _jxj.cfe, _jxj.cfs, _jxj.si, _jxk.cfe, _jxk.cfs, _jxk.si, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_2]}]> but was:<[{indexVersion=1497614187568,generation=2,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jvx.fdt, _jvx.fdx, _jvx.fnm, _jvx.nvd, _jvx.nvm, _jvx.si, _jvx_Lucene50_0.doc, _jvx_Lucene50_0.tim, _jvx_Lucene50_0.tip, _jwr.cfe, _jwr.cfs, _jwr.si, _jws.fdt, _jws.fdx, _jws.fnm, _jws.nvd, _jws.nvm, _jws.si, _jws_Lucene50_0.doc, _jws_Lucene50_0.tim, _jws_Lucene50_0.tip, _jwt.cfe, _jwt.cfs, _jwt.si, _jwu.cfe, _jwu.cfs, _jwu.si, _jwv.cfe, _jwv.cfs, _jwv.si, _jww.cfe, _jww.cfs, _jww.si, _jwx.cfe, _jwx.cfs, _jwx.si, _jwy.cfe, _jwy.cfs, _jwy.si, _jwz.cfe, _jwz.cfs, _jwz.si, _jx0.cfe, _jx0.cfs, _jx0.si, _jx1.cfe, _jx1.cfs, _jx1.si, _jx2.cfe, _jx2.cfs, _jx2.si, _jx3.cfe, _jx3.cfs, _jx3.si, _jx4.cfe, _jx4.cfs, _jx4.si, _jx5.cfe, _jx5.cfs, _jx5.si, _jx6.cfe, _jx6.cfs, _jx6.si, _jx7.cfe, _jx7.cfs, _jx7.si, _jx8.cfe, _jx8.cfs, _jx8.si, _jx9.cfe, _jx9.cfs, _jx9.si, _jxa.cfe, _jxa.cfs, _jxa.si, _jxb.cfe, _jxb.cfs, _jxb.si, _jxc.cfe, _jxc.cfs, _jxc.si, _jxd.cfe, _jxd.cfs, _jxd.si, _jxe.cfe, _jxe.cfs, _jxe.si, _jxf.cfe, _jxf.cfs, _jxf.si, _jxg.cfe, _jxg.cfs, _jxg.si, _jxh.cfe, _jxh.cfs, _jxh.si, _jxi.cfe, _jxi.cfs, _jxi.si, _jxj.cfe, _jxj.cfs, _jxj.si, _jxk.cfe, _jxk.cfs, _jxk.si, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_2]}, {indexVersion=1497614187568,generation=3,filelist=[_6wd.fdt, _6wd.fdx, _6wd.fnm, _6wd.nvd, _6wd.nvm, _6wd.si, _6wd_Lucene50_0.doc, _6wd_Lucene50_0.tim, _6wd_Lucene50_0.tip, _dpe.fdt, _dpe.fdx, _dpe.fnm, _dpe.nvd, _dpe.nvm, _dpe.si, _dpe_Lucene50_0.doc, _dpe_Lucene50_0.tim, _dpe_Lucene50_0.tip, _jxk.cfe, _jxk.cfs, _jxk.si, _jxl.fdt, _jxl.fdx, _jxl.fnm, _jxl.nvd, _jxl.nvm, _jxl.si, _jxl_Lucene50_0.doc, _jxl_Lucene50_0.tim, _jxl_Lucene50_0.tip, _jxm.cfe, _jxm.cfs, _jxm.si, _jxn.cfe, _jxn.cfs, _jxn.si, _jxo.cfe, _jxo.cfs, _jxo.si, _jxp.cfe, _jxp.cfs, _jxp.si, _jxq.cfe, _jxq.cfs, _jxq.si, _jxr.cfe, _jxr.cfs, _jxr.si, _jxs.cfe, _jxs.cfs, _jxs.si, _jxt.cfe, _jxt.cfs, _jxt.si, _jxu.cfe, _jxu.cfs, _jxu.si, _jxv.cfe, _jxv.cfs, _jxv.si, _jxw.cfe, _jxw.cfs, _jxw.si, _jxx.cfe, _jxx.cfs, _jxx.si, _jxy.cfe, _jxy.cfs, _jxy.si, _jxz.cfe, _jxz.cfs, _jxz.si, _jy0.cfe, _jy0.cfs, _jy0.si, _jy1.cfe, _jy1.cfs, _jy1.si, _jy2.cfe, _jy2.cfs, _jy2.si, _jy3.cfe, _jy3.cfs, _jy3.si, _jy4.cfe, _jy4.cfs, _jy4.si, _jy5.cfe, _jy5.cfs, _jy5.si, _jy6.cfe, _jy6.cfs, _jy6.si, _jy7.cfe, _jy7.cfs, _jy7.si, _jy8.cfe, _jy8.cfs, _jy8.si, _jy9.cfe, _jy9.cfs, _jy9.si, _jya.cfe, _jya.cfs, _jya.si, _jyb.cfe, _jyb.cfs, _jyb.si, _jyc.cfe, _jyc.cfs, _jyc.si, segments_3]}]> at __randomizedtesting.SeedInfo.seed([B693392A06F62FF5:9344221A76BE21F6]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:147) at org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1279) 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.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp Error Message: No such processor Template Stack Trace: org.apache.solr.common.SolrException: No such processor Template at __randomizedtesting.SeedInfo.seed([B693392A06F62FF5:132179E2A0B8E601]:0) at org.apache.solr.update.processor.UpdateRequestProcessorChain.getReqProcessors(UpdateRequestProcessorChain.java:284) at org.apache.solr.update.processor.UpdateRequestProcessorChain.constructChain(UpdateRequestProcessorChain.java:236) at org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1446) at org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testRequestTimeUrp(UpdateRequestProcessorFactoryTest.java:54) 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 11499 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPISolrJTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/init-core-data-001 [junit4] 2> 0 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 93 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.e.j.u.log Logging initialized @7288ms [junit4] 2> 100 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 680 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001 [junit4] 2> 694 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 705 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 706 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 905 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55216 [junit4] 2> 1006 ERROR (Thread-1) [ ] 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> 1306 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15cb0937f210000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1685 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1699 INFO (jetty-launcher-1-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1696 INFO (jetty-launcher-1-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1702 INFO (jetty-launcher-1-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1827 INFO (jetty-launcher-1-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@446762e3{/solr,null,AVAILABLE} [junit4] 2> 1828 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@557a21c1{/solr,null,AVAILABLE} [junit4] 2> 1828 INFO (jetty-launcher-1-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e206c47{/solr,null,AVAILABLE} [junit4] 2> 1852 INFO (jetty-launcher-1-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a676fe8{/solr,null,AVAILABLE} [junit4] 2> 1903 INFO (jetty-launcher-1-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47a77e40{SSL,[ssl, http/1.1]}{127.0.0.1:53224} [junit4] 2> 1904 INFO (jetty-launcher-1-thread-2) [ ] o.e.j.s.Server Started @9120ms [junit4] 2> 1904 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53224} [junit4] 2> 1906 INFO (jetty-launcher-1-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@670b2d8d{SSL,[ssl, http/1.1]}{127.0.0.1:39510} [junit4] 2> 1910 INFO (jetty-launcher-1-thread-3) [ ] o.e.j.s.Server Started @9126ms [junit4] 2> 1906 INFO (jetty-launcher-1-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@77810329{SSL,[ssl, http/1.1]}{127.0.0.1:55091} [junit4] 2> 1910 INFO (jetty-launcher-1-thread-4) [ ] o.e.j.s.Server Started @9126ms [junit4] 2> 1907 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7c5cd58f{SSL,[ssl, http/1.1]}{127.0.0.1:34108} [junit4] 2> 1911 INFO (jetty-launcher-1-thread-1) [ ] o.e.j.s.Server Started @9127ms [junit4] 2> 1910 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39510} [junit4] 2> 1910 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55091} [junit4] 2> 1912 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34108} [junit4] 2> 1959 ERROR (jetty-launcher-1-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1959 ERROR (jetty-launcher-1-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1959 ERROR (jetty-launcher-1-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1959 ERROR (jetty-launcher-1-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1966 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1960 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1959 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1972 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1973 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1976 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1976 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1967 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 1976 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1976 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1984 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1984 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2001 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-16T11:04:36.483Z [junit4] 2> 2003 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-16T11:04:36.475Z [junit4] 2> 2003 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-16T11:04:36.475Z [junit4] 2> 2017 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-16T11:04:36.473Z [junit4] 2> 2051 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2192 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2195 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2195 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2202 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2472 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15cb0937f210001, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 2482 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15cb0937f210004, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3067 INFO (jetty-launcher-1-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 3075 INFO (jetty-launcher-1-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 3075 INFO (jetty-launcher-1-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 3075 INFO (jetty-launcher-1-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55216/solr [junit4] 2> 3605 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3605 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3611 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3615 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53224_solr [junit4] 2> 3615 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3622 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.c.Overseer Overseer (id=98147439434858508-127.0.0.1:53224_solr-n_0000000000) starting [junit4] 2> 3642 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34108_solr [junit4] 2> 3642 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39510_solr [junit4] 2> 3660 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3660 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3669 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3669 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3688 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55091_solr [junit4] 2> 3692 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3693 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3693 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3694 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4125 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53224_solr [junit4] 2> 4129 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4129 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4129 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4130 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4747 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53224.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4749 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53224.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4749 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53224.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4751 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55091.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4756 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55091.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4756 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55091.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4764 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39510.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4765 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39510.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4765 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39510.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4766 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34108.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4766 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34108.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4766 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34108.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 4852 INFO (jetty-launcher-1-thread-1) [n:127.0.0.1:34108_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node1/. [junit4] 2> 4856 INFO (jetty-launcher-1-thread-2) [n:127.0.0.1:53224_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node2/. [junit4] 2> 4893 INFO (jetty-launcher-1-thread-4) [n:127.0.0.1:55091_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node4/. [junit4] 2> 4912 INFO (jetty-launcher-1-thread-3) [n:127.0.0.1:39510_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node3/. [junit4] 2> 5498 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 5501 INFO (SUITE-CollectionsAPISolrJTest-seed#[B693392A06F62FF5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55216/solr ready [junit4] 2> 5593 INFO (TEST-CollectionsAPISolrJTest.testCreateCollectionWithPropertyParam-seed#[B693392A06F62FF5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionWithPropertyParam [junit4] 2> 6440 INFO (qtp895565924-43) [n:127.0.0.1:34108_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&property.ulogDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/ulogDir-ggg&property.dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/dataDir-sx&collection.configName=conf&name=solrj_test_core_props&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 6640 INFO (OverseerThreadFactory-18-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.CreateCollectionCmd Create collection solrj_test_core_props [junit4] 2> 6930 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&property.dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/dataDir-sx&collection.configName=conf&newCollection=true&collection=solrj_test_core_props&version=2&replicaType=NRT&property.ulogDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/ulogDir-ggg&name=solrj_test_core_props_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin [junit4] 2> 6943 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 7092 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_test_core_props/state.json] for collection [solrj_test_core_props] has occurred - updating... (live nodes size: [4]) [junit4] 2> 8091 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 8243 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.s.IndexSchema [solrj_test_core_props_shard1_replica_n1] Schema name=minimal [junit4] 2> 8454 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 8455 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'solrj_test_core_props_shard1_replica_n1' using configuration from collection solrj_test_core_props, trusted=true [junit4] 2> 8485 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53224.solr.core.solrj_test_core_props.shard1.replica_n1' (registry 'solr.core.solrj_test_core_props.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 8494 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 8520 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SolrCore [[solrj_test_core_props_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node2/solrj_test_core_props_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/dataDir-sx/] [junit4] 2> 10040 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 10040 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 10062 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 10063 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 10100 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@217cbd82[solrj_test_core_props_shard1_replica_n1] main] [junit4] 2> 10157 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 10199 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 10310 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 10535 INFO (searcherExecutor-27-thread-1-processing-n:127.0.0.1:53224_solr x:solrj_test_core_props_shard1_replica_n1 s:shard1 c:solrj_test_core_props) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SolrCore [solrj_test_core_props_shard1_replica_n1] Registered new searcher Searcher@217cbd82[solrj_test_core_props_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 10540 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570359041105854464 [junit4] 2> 10615 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_test_core_props/state.json] for collection [solrj_test_core_props] has occurred - updating... (live nodes size: [4]) [junit4] 2> 10622 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 10622 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 10623 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:53224/solr/solrj_test_core_props_shard1_replica_n1/ [junit4] 2> 10640 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 10641 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:53224/solr/solrj_test_core_props_shard1_replica_n1/ has no replicas [junit4] 2> 10641 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 10697 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:53224/solr/solrj_test_core_props_shard1_replica_n1/ shard1 [junit4] 2> 10809 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_test_core_props/state.json] for collection [solrj_test_core_props] has occurred - updating... (live nodes size: [4]) [junit4] 2> 10832 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 10846 INFO (qtp1763107271-48) [n:127.0.0.1:53224_solr c:solrj_test_core_props s:shard1 x:solrj_test_core_props_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&property.dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/dataDir-sx&collection.configName=conf&newCollection=true&collection=solrj_test_core_props&version=2&replicaType=NRT&property.ulogDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/ulogDir-ggg&name=solrj_test_core_props_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin} status=0 QTime=3924 [junit4] 2> 10941 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_test_core_props/state.json] for collection [solrj_test_core_props] has occurred - updating... (live nodes size: [4]) [junit4] 2> 10950 INFO (qtp895565924-43) [n:127.0.0.1:34108_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> 10952 INFO (qtp895565924-43) [n:127.0.0.1:34108_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&property.ulogDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/ulogDir-ggg&property.dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/testPropertyParamsForCreate-001/dataDir-sx&collection.configName=conf&name=solrj_test_core_props&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=4559 [junit4] 2> 10987 INFO (qtp1763107271-63) [n:127.0.0.1:53224_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={action=STATUS&indexInfo=true&wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 10991 INFO (TEST-CollectionsAPISolrJTest.testCreateCollectionWithPropertyParam-seed#[B693392A06F62FF5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionWithPropertyParam [junit4] 2> 11042 INFO (TEST-CollectionsAPISolrJTest.testOverseerStatus-seed#[B693392A06F62FF5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerStatus [junit4] 2> 11045 INFO (qtp895565924-46) [n:127.0.0.1:34108_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 11081 INFO (OverseerCollectionConfigSetProcessor-98147439434858508-127.0.0.1:53224_solr-n_0000000000) [n:127.0.0.1:53224_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> 11202 INFO (qtp895565924-46) [n:127.0.0.1:34108_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=157 [junit4] 2> 11205 INFO (TEST-CollectionsAPISolrJTest.testOverseerStatus-seed#[B693392A06F62FF5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerStatus [junit4] 2> 11207 INFO (OverseerCollectionConfigSetProcessor-98147439434858508-127.0.0.1:53224_solr-n_0000000000) [n:127.0.0.1:53224_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> 11252 INFO (TEST-CollectionsAPISolrJTest.testBalanceShardUnique-seed#[B693392A06F62FF5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBalanceShardUnique [junit4] 2> 11254 INFO (qtp895565924-38) [n:127.0.0.1:34108_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&name=balancedProperties&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 11267 INFO (OverseerThreadFactory-18-thread-3-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.CreateCollectionCmd Create collection balancedProperties [junit4] 2> 11746 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=balancedProperties_shard2_replica_n2&action=CREATE&numShards=2&collection=balancedProperties&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 11846 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=balancedProperties_shard2_replica_n1&action=CREATE&numShards=2&collection=balancedProperties&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 11847 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 11862 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=balancedProperties_shard1_replica_n2&action=CREATE&numShards=2&collection=balancedProperties&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 11863 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 11922 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=balancedProperties_shard1_replica_n1&action=CREATE&numShards=2&collection=balancedProperties&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 11923 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 12034 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 12034 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 12034 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 12035 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 12810 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 12859 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.s.IndexSchema [balancedProperties_shard2_replica_n2] Schema name=minimal [junit4] 2> 12871 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 12871 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'balancedProperties_shard2_replica_n2' using configuration from collection balancedProperties, trusted=true [junit4] 2> 12895 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53224.solr.core.balancedProperties.shard2.replica_n2' (registry 'solr.core.balancedProperties.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 12895 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 12896 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SolrCore [[balancedProperties_shard2_replica_n2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node2/balancedProperties_shard2_replica_n2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node2/./balancedProperties_shard2_replica_n2/data/] [junit4] 2> 12903 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 12956 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 12984 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.s.IndexSchema [balancedProperties_shard2_replica_n1] Schema name=minimal [junit4] 2> 12996 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 13015 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 13015 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'balancedProperties_shard2_replica_n1' using configuration from collection balancedProperties, trusted=true [junit4] 2> 13017 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55091.solr.core.balancedProperties.shard2.replica_n1' (registry 'solr.core.balancedProperties.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 13017 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 13018 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.c.SolrCore [[balancedProperties_shard2_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node4/balancedProperties_shard2_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node4/./balancedProperties_shard2_replica_n1/data/] [junit4] 2> 13031 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.s.IndexSchema [balancedProperties_shard1_replica_n1] Schema name=minimal [junit4] 2> 13033 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.s.IndexSchema [balancedProperties_shard1_replica_n2] Schema name=minimal [junit4] 2> 13057 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 13057 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'balancedProperties_shard1_replica_n2' using configuration from collection balancedProperties, trusted=true [junit4] 2> 13059 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34108.solr.core.balancedProperties.shard1.replica_n2' (registry 'solr.core.balancedProperties.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 13059 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 13059 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.SolrCore [[balancedProperties_shard1_replica_n2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node1/balancedProperties_shard1_replica_n2], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node1/./balancedProperties_shard1_replica_n2/data/] [junit4] 2> 13066 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 13066 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'balancedProperties_shard1_replica_n1' using configuration from collection balancedProperties, trusted=true [junit4] 2> 13067 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39510.solr.core.balancedProperties.shard1.replica_n1' (registry 'solr.core.balancedProperties.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@377b1f4c [junit4] 2> 13067 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 13067 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.c.SolrCore [[balancedProperties_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node3/balancedProperties_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_B693392A06F62FF5-001/tempDir-001/node3/./balancedProperties_shard1_replica_n1/data/] [junit4] 2> 13084 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 13085 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 13095 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 13095 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 13098 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@60845bf3[balancedProperties_shard2_replica_n2] main] [junit4] 2> 13107 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 13108 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 13109 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 13110 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570359043800694784 [junit4] 2> 13114 INFO (searcherExecutor-32-thread-1-processing-n:127.0.0.1:53224_solr x:balancedProperties_shard2_replica_n2 s:shard2 c:balancedProperties) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SolrCore [balancedProperties_shard2_replica_n2] Registered new searcher Searcher@60845bf3[balancedProperties_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 13125 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9999ms [junit4] 2> 13210 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 13210 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 13228 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 13228 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 13231 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@635c5e2a[balancedProperties_shard2_replica_n1] main] [junit4] 2> 13235 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 13235 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 13236 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13237 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13237 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13237 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13239 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 13243 INFO (searcherExecutor-33-thread-1-processing-n:127.0.0.1:55091_solr x:balancedProperties_shard2_replica_n1 s:shard2 c:balancedProperties) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.c.SolrCore [balancedProperties_shard2_replica_n1] Registered new searcher Searcher@635c5e2a[balancedProperties_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 13244 INFO (qtp741489790-54) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570359043941203968 [junit4] 2> 13254 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 13254 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 13262 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 13262 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 13266 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@43d2e908[balancedProperties_shard1_replica_n1] main] [junit4] 2> 13267 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 13268 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 13269 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 13272 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 13272 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 13276 INFO (searcherExecutor-36-thread-1-processing-n:127.0.0.1:39510_solr x:balancedProperties_shard1_replica_n1 s:shard1 c:balancedProperties) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.c.SolrCore [balancedProperties_shard1_replica_n1] Registered new searcher Searcher@43d2e908[balancedProperties_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 13280 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 13281 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 13285 INFO (qtp1423950514-35) [n:127.0.0.1:39510_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570359043984195584 [junit4] 2> 13289 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c960505[balancedProperties_shard1_replica_n2] main] [junit4] 2> 13294 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 13295 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 13296 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 13298 INFO (searcherExecutor-35-thread-1-processing-n:127.0.0.1:34108_solr x:balancedProperties_shard1_replica_n2 s:shard1 c:balancedProperties) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.SolrCore [balancedProperties_shard1_replica_n2] Registered new searcher Searcher@3c960505[balancedProperties_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 13302 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570359044002021376 [junit4] 2> 13423 INFO (qtp895565924-44) [n:127.0.0.1:34108_solr c:balancedProperties s:shard1 x:balancedProperties_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 13530 INFO (zkCallback-23-thread-1-processing-n:127.0.0.1:53224_solr) [n:127.0.0.1:53224_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13530 INFO (zkCallback-20-thread-1-processing-n:127.0.0.1:39510_solr) [n:127.0.0.1:39510_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13530 INFO (zkCallback-21-thread-1-processing-n:127.0.0.1:34108_solr) [n:127.0.0.1:34108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13530 INFO (zkCallback-22-thread-1-processing-n:127.0.0.1:55091_solr) [n:127.0.0.1:55091_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/balancedProperties/state.json] for collection [balancedProperties] has occurred - updating... (live nodes size: [4]) [junit4] 2> 13633 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 13633 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 13633 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:53224/solr/balancedProperties_shard2_replica_n2/ [junit4] 2> 13640 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.PeerSync PeerSync: core=balancedProperties_shard2_replica_n2 url=https://127.0.0.1:53224/solr START replicas=[https://127.0.0.1:55091/solr/balancedProperties_shard2_replica_n1/] nUpdates=100 [junit4] 2> 13643 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.u.PeerSync PeerSync: core=balancedProperties_shard2_replica_n2 url=https://127.0.0.1:53224/solr DONE. We have no versions. sync failed. [junit4] 2> 13662 INFO (qtp741489790-55) [n:127.0.0.1:55091_solr c:balancedProperties s:shard2 r:core_node2 x:balancedProperties_shard2_replica_n1] o.a.s.c.S.Request [balancedProperties_shard2_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 13668 INFO (qtp1763107271-64) [n:127.0.0.1:53224_solr c:balancedProperties s:shard2 x:balancedProperties_shard2_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidat [...truncated too long message...] xec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:498) [junit4] 2> ... 9 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused) [junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) [junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) [junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) [junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [junit4] 2> at java.net.Socket.connect(Socket.java:589) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 19 more [junit4] 2> [junit4] 2> 3130626 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4394bb3c{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 3130626 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=628667740 [junit4] 2> 3130626 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3130628 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3130630 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3130630 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 3130631 INFO (coreCloseExecutor-12557-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@66218a49 [junit4] 2> 3130634 INFO (coreCloseExecutor-12557-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1713474121 [junit4] 2> 3130638 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@530dff68{/solr,null,UNAVAILABLE} [junit4] 2> 3130639 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5d5cc252{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 3130639 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=476143070 [junit4] 2> 3130640 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3130640 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3130640 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3130640 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 3130640 INFO (coreCloseExecutor-12559-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1210b05d [junit4] 2> 3130641 INFO (coreCloseExecutor-12559-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=303083613 [junit4] 2> 3130642 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[B693392A06F62FF5]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@772c6632{/solr,null,UNAVAILABLE} [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_B693392A06F62FF5-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=Lucene50(blocksize=128), id=Lucene50(blocksize=128), newname=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=732, maxMBSortInHeap=7.485847824316912, sim=RandomSimilarity(queryNorm=true): {}, locale=en-CA, timezone=MST [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=151318544,total=525860864 [junit4] 2> NOTE: All tests run in this JVM: [CdcrBootstrapTest, FileBasedSpellCheckerTest, TestCopyFieldCollectionResource, TestSolrFieldCacheBean, BigEndianAscendingWordDeserializerTest, TestNumericTerms64, TemplateUpdateProcessorTest, TestIntervalFaceting, RollingRestartTest, TestSSLRandomization, FacetPivotSmallTest, TestFieldCacheSort, TestSchemaSimilarityResource, HdfsRecoverLeaseTest, ClassificationUpdateProcessorFactoryTest, MultiTermTest, OverriddenZkACLAndCredentialsProvidersTest, HdfsUnloadDistributedZkTest, PeerSyncWithIndexFingerprintCachingTest, TestFaceting, PreAnalyzedFieldTest, TestConfigSetsAPIZkFailure, TestSolrConfigHandler, EnumFieldTest, AnalysisAfterCoreReloadTest, TestDistribDocBasedVersion, CacheHeaderTest, HdfsSyncSliceTest, TestStressLucene, PathHierarchyTokenizerFactoryTest, SecurityConfHandlerTest, DisMaxRequestHandlerTest, CoreAdminCreateDiscoverTest, TestTolerantUpdateProcessorRandomCloud, CollectionTooManyReplicasTest, TestRestManager, TestSmileRequest, UUIDUpdateProcessorFallbackTest, HdfsBasicDistributedZk2Test, TestDistributedSearch, TestClusterStateMutator, HttpPartitionTest, TestFieldSortValues, TestStressLiveNodes, TestSolrCoreProperties, TestCloudInspectUtil, TestPseudoReturnFields, TestReplicaProperties, URLClassifyProcessorTest, ConnectionReuseTest, ResponseLogComponentTest, DistributedFacetPivotSmallAdvancedTest, UniqFieldsUpdateProcessorFactoryTest, TestAtomicUpdateErrorCases, RegexBoostProcessorTest, TestRandomFaceting, ParsingFieldUpdateProcessorsTest, TestChildDocTransformer, TestFieldCacheWithThreads, XsltUpdateRequestHandlerTest, VMParamsZkACLAndCredentialsProvidersTest, StandardRequestHandlerTest, CopyFieldTest, TestCrossCoreJoin, ReplaceNodeTest, TestReqParamsAPI, TestCodecSupport, TestHdfsCloudBackupRestore, TestSolrCloudWithHadoopAuthPlugin, TestReloadDeadlock, SpatialRPTFieldTypeTest, TestFunctionQuery, DateMathParserTest, TestOnReconnectListenerSupport, TimeZoneUtilsTest, TestLMJelinekMercerSimilarityFactory, TestReplicationHandler] [junit4] Completed [450/725 (3!)] on J0 in 617.82s, 16 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 6168 lines...] [junit4] Suite: org.apache.solr.handler.extraction.TestExtractionDateUtil [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestExtractionDateUtil -Dtests.method=testParseDate -Dtests.seed=1405E46F66B64616 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=mk-MK -Dtests.timezone=America/Metlakatla -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 0.05s J1 | TestExtractionDateUtil.testParseDate <<< [junit4] > Throwable #1: java.lang.AssertionError: Incorrect parsed timestamp: 1226583351000 != 1226579751000 (Thu Nov 13 04:35:51 AKST 2008) [junit4] > at __randomizedtesting.SeedInfo.seed([1405E46F66B64616:5E1C9C5A1D1F31A3]:0) [junit4] > at org.apache.solr.handler.extraction.TestExtractionDateUtil.assertParsedDate(TestExtractionDateUtil.java:59) [junit4] > at org.apache.solr.handler.extraction.TestExtractionDateUtil.testParseDate(TestExtractionDateUtil.java:54) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1846, maxMBSortInHeap=5.002113897958544, sim=RandomSimilarity(queryNorm=true): {}, locale=mk-MK, timezone=America/Metlakatla [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=200049424,total=253231104 [junit4] 2> NOTE: All tests run in this JVM: [TestExtractionDateUtil] [junit4] Completed [1/4 (1!)] on J1 in 1.46s, 2 tests, 1 failure <<< FAILURES! [...truncated 38100 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org