Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/5/
4 tests failed. FAILED: org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN Error Message: expected:<1.0> but was:<0.0> Stack Trace: java.lang.AssertionError: expected:<1.0> but was:<0.0> at __randomizedtesting.SeedInfo.seed([C3F479E35220566C:32550BB3679B9CFE]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:443) at org.junit.Assert.assertEquals(Assert.java:512) at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:256) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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.ZkControllerTest.testPublishAndWaitForDownStates Error Message: The ZkController.publishAndWaitForDownStates should have timed out but it didn't Stack Trace: java.lang.AssertionError: The ZkController.publishAndWaitForDownStates should have timed out but it didn't at __randomizedtesting.SeedInfo.seed([8078486235829B49:A777248E9415D476]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.ZkControllerTest.testPublishAndWaitForDownStates(ZkControllerTest.java:306) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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.core.TestJmxIntegration.testJmxOnCoreReload Error Message: Number of registered MBeans is not the same as info registry size expected:<107> but was:<0> Stack Trace: java.lang.AssertionError: Number of registered MBeans is not the same as info registry size expected:<107> but was:<0> at __randomizedtesting.SeedInfo.seed([8078486235829B49:82C1930E46C14784]: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:472) at org.apache.solr.core.TestJmxIntegration.testJmxOnCoreReload(TestJmxIntegration.java:180) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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.doTestIndexFetchOnMasterRestart Error Message: IOException occured when talking to server at: http://127.0.0.1:55132/solr/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:55132/solr/collection1 at __randomizedtesting.SeedInfo.seed([8078486235829B49:588F8C869E595915]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152) at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:180) at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:643) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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) Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:55132 failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542) ... 47 more Build Log: [...truncated 12386 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> 221291 INFO (SUITE-TestReplicationHandler-seed#[8078486235829B49]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/init-core-data-001 [junit4] 2> 221291 WARN (SUITE-TestReplicationHandler-seed#[8078486235829B49]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=30 numCloses=30 [junit4] 2> 221294 INFO (SUITE-TestReplicationHandler-seed#[8078486235829B49]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 221295 INFO (SUITE-TestReplicationHandler-seed#[8078486235829B49]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 221298 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchOnMasterRestart [junit4] 2> 221299 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1 [junit4] 2> 221305 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 221317 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 221317 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 221317 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 221317 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d387ce9{/solr,null,AVAILABLE} [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26b1a399{HTTP/1.1,[http/1.1]}{127.0.0.1:55132} [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server Started @224614ms [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=55132} [junit4] 2> 221318 ERROR (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 221318 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 221319 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-05T04:50:59.130Z [junit4] 2> 221319 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/solr.xml [junit4] 2> 221331 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 221331 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 221350 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 221396 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221409 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221409 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221412 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/. [junit4] 2> 221412 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 221412 INFO (coreLoadExecutor-589-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 221436 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 221446 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 221450 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 221450 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 221451 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221451 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 221451 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/./collection1/data/] [junit4] 2> 221461 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2145231875, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 221515 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 221515 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 221516 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=25.53515625, floorSegmentMB=1.4501953125, forceMergeDeletesPctAllowed=2.850707588522434, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 221517 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@72fd312a[collection1] main] [junit4] 2> 221517 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1/conf [junit4] 2> 221518 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 221518 INFO (coreLoadExecutor-589-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 221521 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/collection1 [junit4] 2> 221522 INFO (searcherExecutor-590-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@72fd312a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 221527 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 221528 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 221529 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 221529 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 221529 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@290fb136{/solr,null,AVAILABLE} [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ef4d133{HTTP/1.1,[http/1.1]}{127.0.0.1:34354} [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server Started @224825ms [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=34354} [junit4] 2> 221530 ERROR (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 221530 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-05T04:50:59.341Z [junit4] 2> 221531 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/solr.xml [junit4] 2> 221535 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 221535 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 221537 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 221583 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221598 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221598 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221601 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/. [junit4] 2> 221601 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 221601 INFO (coreLoadExecutor-599-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 221620 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 221630 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 221632 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 221632 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 221632 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 221632 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 221633 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/./collection1/data/] [junit4] 2> 221635 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2145231875, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 221717 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 221717 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 221719 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=25.53515625, floorSegmentMB=1.4501953125, forceMergeDeletesPctAllowed=2.850707588522434, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 221719 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46cdd44c[collection1] main] [junit4] 2> 221720 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-002/collection1/conf [junit4] 2> 221739 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 221739 INFO (coreLoadExecutor-599-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 221741 INFO (searcherExecutor-600-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46cdd44c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 221744 INFO (qtp1376169545-1259) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 221753 INFO (qtp1376169545-1263) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 221754 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 221754 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 221754 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 221754 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 221754 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 221757 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 221757 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4714c6f commitCommandVersion:0 [junit4] 2> 221760 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@26b1a399{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 221760 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7adf095f[collection1] main] [junit4] 2> 221760 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1893957657 [junit4] 2> 221760 INFO (indexFetcher-604-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 221761 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 221761 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1aa9652: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@8b95792 [junit4] 2> 221765 INFO (searcherExecutor-600-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7adf095f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 221767 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 221767 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7919dae2: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7ca983a8 [junit4] 2> 221770 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 221770 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@731894af: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@225bd3f5 [junit4] 2> 221788 INFO (coreCloseExecutor-605-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5e35c447 [junit4] 2> 221788 INFO (coreCloseExecutor-605-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1580581959 [junit4] 2> 221789 INFO (coreCloseExecutor-605-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36031b2e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@74922028 [junit4] 2> 221835 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2d387ce9{/solr,null,UNAVAILABLE} [junit4] 2> 221835 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 221836 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 221846 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 221846 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 221846 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 221847 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7315bcc2{/solr,null,AVAILABLE} [junit4] 2> 221847 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45c83efa{HTTP/1.1,[http/1.1]}{127.0.0.1:55132} [junit4] 2> 221847 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.Server Started @225143ms [junit4] 2> 221847 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=55132} [junit4] 2> 221848 ERROR (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 221848 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 221848 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 221848 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 221848 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-05T04:50:59.659Z [junit4] 2> 221849 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/solr.xml [junit4] 2> 221853 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 221853 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 221854 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 222028 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222046 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222047 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222060 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/. [junit4] 2> 222060 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 222061 INFO (coreLoadExecutor-612-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 222089 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 222099 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 222101 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 222101 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 222101 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222118 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 222118 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/./collection1/data/] [junit4] 2> 222125 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=8.701171875, floorSegmentMB=1.8095703125, forceMergeDeletesPctAllowed=19.07167397011207, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 222315 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 222315 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 222317 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=31.935546875, floorSegmentMB=0.7099609375, forceMergeDeletesPctAllowed=18.76565992128332, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 222317 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5795b133[collection1] main] [junit4] 2> 222318 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-001/collection1/conf [junit4] 2> 222319 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 222319 INFO (coreLoadExecutor-612-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 222321 INFO (searcherExecutor-613-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5795b133[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 222331 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIndexFetchOnMasterRestart [junit4] 2> 222331 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@45c83efa{HTTP/1.1,[http/1.1]}{127.0.0.1:55132} [junit4] 2> 222332 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1032913585 [junit4] 2> 222335 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 222336 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@67b6f033: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5bd8bbd1 [junit4] 2> 222343 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 222343 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1847c7f0: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7ca983a8 [junit4] 2> 222359 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 222359 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a2eae28: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@225bd3f5 [junit4] 2> 222361 INFO (coreCloseExecutor-617-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3491c537 [junit4] 2> 222361 INFO (coreCloseExecutor-617-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=881968439 [junit4] 2> 222361 INFO (coreCloseExecutor-617-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16287e12: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1c16b0d0 [junit4] 2> 222382 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7315bcc2{/solr,null,UNAVAILABLE} [junit4] 2> 222382 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 222383 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3ef4d133{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 222384 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=882853448 [junit4] 2> 222385 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 222385 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@37f49b98: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4810dd7a [junit4] 2> 222387 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 222387 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ec1d4a1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7ca983a8 [junit4] 2> 222388 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 222388 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41e2195: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@225bd3f5 [junit4] 2> 222404 INFO (coreCloseExecutor-619-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14769839 [junit4] 2> 222404 INFO (coreCloseExecutor-619-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=343316537 [junit4] 2> 222404 INFO (coreCloseExecutor-619-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2cfa41cb: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2be6aa9 [junit4] 2> 222419 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@290fb136{/solr,null,UNAVAILABLE} [junit4] 2> 222419 INFO (TEST-TestReplicationHandler.doTestIndexFetchOnMasterRestart-seed#[8078486235829B49]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.method=doTestIndexFetchOnMasterRestart -Dtests.seed=8078486235829B49 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=zh -Dtests.timezone=Etc/GMT-2 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 1.14s J1 | TestReplicationHandler.doTestIndexFetchOnMasterRestart <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:55132/solr/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([8078486235829B49:588F8C869E595915]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152) [junit4] > at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:180) [junit4] > at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:643) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:55132 failed to respond [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) [junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) [junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) [junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) [junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) [junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) [junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) [junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542) [junit4] > ... 47 more [junit4] 2> 222432 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStressReplication [junit4] 2> 222433 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/collection1 [junit4] 2> 222436 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 222445 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 222445 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 222445 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 222448 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d9f29ff{/solr,null,AVAILABLE} [junit4] 2> 222448 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@715f68dc{HTTP/1.1,[http/1.1]}{127.0.0.1:50615} [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.Server Started @225744ms [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=50615} [junit4] 2> 222449 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 222449 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-05T04:51:00.260Z [junit4] 2> 222450 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/solr.xml [junit4] 2> 222463 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 222463 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 222464 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 222519 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222531 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222534 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222539 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/. [junit4] 2> 222539 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 222539 INFO (coreLoadExecutor-626-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 222578 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 222594 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 222597 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 222597 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/./collection1, trusted=true [junit4] 2> 222597 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222597 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 222597 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/./collection1/data/] [junit4] 2> 222600 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2145231875, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 222654 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 222654 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 222655 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=25.53515625, floorSegmentMB=1.4501953125, forceMergeDeletesPctAllowed=2.850707588522434, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 222656 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b356630[collection1] main] [junit4] 2> 222656 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-003/collection1/conf [junit4] 2> 222657 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 222657 INFO (coreLoadExecutor-626-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 222659 INFO (searcherExecutor-627-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b356630[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 222660 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/collection1 [junit4] 2> 222663 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 222665 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 222665 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 222665 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 222665 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@29fbfd1{/solr,null,AVAILABLE} [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@72ae4ff4{HTTP/1.1,[http/1.1]}{127.0.0.1:38605} [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.Server Started @225962ms [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=38605} [junit4] 2> 222666 ERROR (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 222666 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-05T04:51:00.477Z [junit4] 2> 222667 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/solr.xml [junit4] 2> 222671 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 222671 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 222672 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 222719 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222733 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222734 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/. [junit4] 2> 222737 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 222737 INFO (coreLoadExecutor-636-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 222750 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 222759 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 222761 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 222761 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/./collection1, trusted=true [junit4] 2> 222762 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@61beb67d [junit4] 2> 222762 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 222762 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/./collection1/data/] [junit4] 2> 222764 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2145231875, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 222822 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 222822 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 222823 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=25.53515625, floorSegmentMB=1.4501953125, forceMergeDeletesPctAllowed=2.850707588522434, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 222823 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dec07b5[collection1] main] [junit4] 2> 222824 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_8078486235829B49-001/solr-instance-004/collection1/conf [junit4] 2> 222826 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 222826 INFO (coreLoadExecutor-636-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 222829 INFO (searcherExecutor-637-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dec07b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 222835 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@72ae4ff4{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 222835 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=332281923 [junit4] 2> 222842 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 222842 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d3e97f9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2b76e460 [junit4] 2> 222842 INFO (qtp2012883132-1322) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 222844 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 222845 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 222845 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 222845 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 222848 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 222851 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 222851 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@13271288 commitCommandVersion:0 [junit4] 2> 222859 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@489ad76b[collection1] main] [junit4] 2> 222859 INFO (indexFetcher-641-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 222861 INFO (searcherExecutor-637-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@489ad76b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 222864 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 222864 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f6e51fb: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7ca983a8 [junit4] 2> 222870 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 222870 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@418d288d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@225bd3f5 [junit4] 2> 222871 INFO (coreCloseExecutor-642-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b251aa3 [junit4] 2> 222871 INFO (coreCloseExecutor-642-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=992287395 [junit4] 2> 222871 INFO (coreCloseExecutor-642-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3072a9cc: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@404f795f [junit4] 2> 222890 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@29fbfd1{/solr,null,UNAVAILABLE} [junit4] 2> 222890 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 222892 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 222901 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 222901 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 222901 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 222902 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@57f0d287{/solr,null,AVAILABLE} [junit4] 2> 222902 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[8078486235829B49]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4030c57c{HTTP/1.1,[http/1.1]}{127.0.0.1:46531} [junit4] 2> 222903 INFO (TEST-TestReplicationHandler.doTestStressReplication-see [...truncated too long message...] TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594073406985207808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1629565 INFO (TEST-TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7fc797c9 commitCommandVersion:1594073406985207808 [junit4] 2> 1629624 INFO (TEST-TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11536066[collection1] main] [junit4] 2> 1629625 INFO (TEST-TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1629628 INFO (searcherExecutor-5374-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11536066[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))} [junit4] 2> 1629633 INFO (TEST-TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 68 [junit4] 2> 1629634 INFO (TEST-TestJmxIntegration.testJmxUpdate-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testJmxUpdate [junit4] 2> 1629654 INFO (TEST-TestJmxIntegration.testJmxRegistration-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testJmxRegistration [junit4] 2> 1629867 INFO (TEST-TestJmxIntegration.testJmxRegistration-seed#[8078486235829B49]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testJmxRegistration [junit4] 2> 1629867 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 1629867 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2079104951 [junit4] 2> 1629868 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1629868 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c6c27c3: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@31a2ffd9 [junit4] 2> 1629879 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1629879 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4b062e5e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2ed62e3f [junit4] 2> 1629883 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1629883 INFO (SUITE-TestJmxIntegration-seed#[8078486235829B49]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6bafbbc: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5dfdd7c1 [junit4] 2> 1629884 INFO (coreCloseExecutor-5379-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@21cae71b [junit4] 2> 1629884 INFO (coreCloseExecutor-5379-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=566945563 [junit4] 2> 1629884 INFO (coreCloseExecutor-5379-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@453e1340: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1024c3c7 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestJmxIntegration_8078486235829B49-001 [junit4] 2> NOTE: test params are: codec=SimpleText, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@574ce036), locale=de-DE, timezone=EST [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=178167376,total=530579456 [junit4] 2> NOTE: All tests run in this JVM: [TestSubQueryTransformer, GraphQueryTest, RecoveryZkTest, DistribCursorPagingTest, TestSha256AuthenticationProvider, CachingDirectoryFactoryTest, TestRetrieveFieldsOptimizer, DistanceUnitsTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, CleanupOldIndexTest, ExplicitHLLTest, TestSolrXml, TestStressReorder, TestLargeCluster, CdcrReplicationDistributedZkTest, TestCorePropertiesReload, TestDistributedMissingSort, TestUtilizeNode, TestFreeTextSuggestions, TestCoreDiscovery, DataDrivenBlockJoinTest, TestCloudJSONFacetJoinDomain, TestAuthorizationFramework, TestSolr4Spatial2, TestRequestStatusCollectionAPI, CoreAdminCreateDiscoverTest, TestPolicyCloud, DistributedQueueTest, TimeZoneUtilsTest, TestPrepRecovery, SpellCheckComponentTest, TestTolerantSearch, TestCoreAdminApis, BlockDirectoryTest, TestRangeQuery, NotRequiredUniqueKeyTest, HdfsBasicDistributedZkTest, TestNumericTokenStream, LeaderFailoverAfterPartitionTest, ReplicaListTransformerTest, TestRandomFlRTGCloud, AutoScalingHandlerTest, TestDFISimilarityFactory, TestDFRSimilarityFactory, TestFieldTypeCollectionResource, TriggerIntegrationTest, LeaderElectionIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, PingRequestHandlerTest, InfoHandlerTest, TestSuggestSpellingConverter, TestSolrConfigHandlerCloud, TestLazyCores, TestSolrCloudWithHadoopAuthPlugin, ResponseLogComponentTest, CollectionsAPIAsyncDistributedZkTest, TestExpandComponent, UnloadDistributedZkTest, ClusterStateTest, SuggesterTSTTest, TestJsonFacetRefinement, DeleteInactiveReplicaTest, TestAddFieldRealTimeGet, VersionInfoTest, TestObjectReleaseTracker, DirectSolrSpellCheckerTest, DistributedSpellCheckComponentTest, IndexSchemaRuntimeFieldTest, TestQuerySenderListener, DeleteReplicaTest, SolrCloudExampleTest, TestHdfsCloudBackupRestore, TestComplexPhraseQParserPlugin, TestMissingGroups, TestOverriddenPrefixQueryForCustomFieldType, WrapperMergePolicyFactoryTest, TestBinaryResponseWriter, CopyFieldTest, HdfsLockFactoryTest, AtomicUpdatesTest, TestTestInjection, FileBasedSpellCheckerTest, TestEmbeddedSolrServerConstructors, SolrCmdDistributorTest, TestTlogReplica, LukeRequestHandlerTest, TestStandardQParsers, BigEndianAscendingWordDeserializerTest, ConnectionReuseTest, ClassificationUpdateProcessorTest, OverseerRolesTest, ReplaceNodeNoTargetTest, TestSolrCoreProperties, TestFaceting, TestInPlaceUpdatesDistrib, TestLockTree, AnalysisAfterCoreReloadTest, CurrencyRangeFacetCloudTest, TestSerializedLuceneMatchVersion, TestLeaderElectionWithEmptyReplica, DateRangeFieldTest, QueryResultKeyTest, SearchHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestMacros, TestAnalyzeInfixSuggestions, HdfsNNFailoverTest, TestHalfAndHalfDocValues, HLLUtilTest, MetricsConfigTest, ZkStateWriterTest, RegexBytesRefFilterTest, AliasIntegrationTest, MergeStrategyTest, TestUseDocValuesAsStored2, TestRandomDVFaceting, TestDynamicFieldResource, V2ApiIntegrationTest, TestBadConfig, TestDistribStateManager, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ClusterStateUpdateTest, OverriddenZkACLAndCredentialsProvidersTest, TestOrdValues, ResponseHeaderTest, NumericFieldsTest, TestJmxIntegration] [junit4] Completed [539/780 (3!)] on J2 in 1.19s, 3 tests, 1 failure <<< FAILURES! [...truncated 8274 lines...] [junit4] Suite: org.apache.solr.ltr.TestLTRReRankingPipeline [junit4] 2> 25061 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[C3F479E35220566C]) [ ] o.a.s.l.TestLTRReRankingPipeline rerank 1 documents [junit4] 2> 25062 INFO (TEST-TestLTRReRankingPipeline.testDifferentTopN-seed#[C3F479E35220566C]) [ ] o.a.s.l.TestLTRReRankingPipeline doc 0 in pos 0 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLTRReRankingPipeline -Dtests.method=testDifferentTopN -Dtests.seed=C3F479E35220566C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ja-JP-u-ca-japanese-x-lvariant-JP -Dtests.timezone=Africa/Niamey -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 0.02s J1 | TestLTRReRankingPipeline.testDifferentTopN <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<1.0> but was:<0.0> [junit4] > at __randomizedtesting.SeedInfo.seed([C3F479E35220566C:32550BB3679B9CFE]:0) [junit4] > at org.apache.solr.ltr.TestLTRReRankingPipeline.testDifferentTopN(TestLTRReRankingPipeline.java:256) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2c26a6d2), locale=ja-JP-u-ca-japanese-x-lvariant-JP, timezone=Africa/Niamey [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=243225240,total=355991552 [junit4] 2> NOTE: All tests run in this JVM: [TestUserTermScoreWithQ, TestValueFeature, TestFieldValueFeature, TestOriginalScoreFeature, TestEdisMaxSolrFeature, TestFeatureExtractionFromMultipleSegments, TestLTRReRankingPipeline] [junit4] Completed [15/37 (1!)] on J1 in 0.13s, 3 tests, 1 failure <<< FAILURES! [...truncated 40365 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org