Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/943/ Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseParallelGC
5 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:56467/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:56467/solr at __randomizedtesting.SeedInfo.seed([D786EC9FD0CE31DD:16769533FD9EFB7A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:56780/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:56780/solr at __randomizedtesting.SeedInfo.seed([D786EC9FD0CE31DD:16769533FD9EFB7A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:56808/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:56808/solr at __randomizedtesting.SeedInfo.seed([D786EC9FD0CE31DD:16769533FD9EFB7A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:57095/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:57095/solr at __randomizedtesting.SeedInfo.seed([D786EC9FD0CE31DD:16769533FD9EFB7A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:64387/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:64387/solr at __randomizedtesting.SeedInfo.seed([D786EC9FD0CE31DD:16769533FD9EFB7A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 14271 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/init-core-data-001 [junit4] 2> 2365332 WARN (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 [junit4] 2> 2365332 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2365333 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth [junit4] 2> 2365333 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2365334 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001 [junit4] 2> 2365334 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2365334 INFO (Thread-4641) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2365334 INFO (Thread-4641) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2365337 ERROR (Thread-4641) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2365442 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:64360 [junit4] 2> 2365453 INFO (zkConnectionManagerCallback-8775-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365460 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9+181 [junit4] 2> 2365460 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9+181 [junit4] 2> 2365461 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2365461 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2365461 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2365462 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50f050e3{/solr,null,AVAILABLE} [junit4] 2> 2365462 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@437c99cb{/solr,null,AVAILABLE} [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44c73f4c{SSL,[ssl, http/1.1]}{127.0.0.1:64387} [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-1) [ ] o.e.j.s.Server Started @2365539ms [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7a052976{SSL,[ssl, http/1.1]}{127.0.0.1:64388} [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64387} [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.e.j.s.Server Started @2365540ms [junit4] 2> 2365463 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64388} [junit4] 2> 2365464 ERROR (jetty-launcher-8772-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2365464 ERROR (jetty-launcher-8772-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.7.0 [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.7.0 [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-20T10:17:01.177400Z [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2365464 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-20T10:17:01.177426Z [junit4] 2> 2365465 INFO (zkConnectionManagerCallback-8778-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365466 INFO (zkConnectionManagerCallback-8779-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365466 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2365466 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2365591 INFO (jetty-launcher-8772-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64360/solr [junit4] 2> 2365592 INFO (zkConnectionManagerCallback-8783-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365597 INFO (zkConnectionManagerCallback-8785-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365783 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64387_solr [junit4] 2> 2365785 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.c.Overseer Overseer (id=72143172801986564-127.0.0.1:64387_solr-n_0000000000) starting [junit4] 2> 2365797 INFO (zkConnectionManagerCallback-8792-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2365803 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64360/solr ready [junit4] 2> 2365805 INFO (OverseerStateUpdate-72143172801986564-127.0.0.1:64387_solr-n_0000000000) [n:127.0.0.1:64387_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64387_solr [junit4] 2> 2365807 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64387_solr [junit4] 2> 2365810 INFO (zkCallback-8791-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2365810 INFO (zkCallback-8784-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2365886 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2365924 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64387.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2365984 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64387.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2365984 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64387.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2365985 INFO (jetty-launcher-8772-thread-1) [n:127.0.0.1:64387_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node1/. [junit4] 2> 2366217 INFO (jetty-launcher-8772-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64360/solr [junit4] 2> 2366219 INFO (zkConnectionManagerCallback-8797-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2366223 INFO (zkConnectionManagerCallback-8799-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2366272 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2366293 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2366293 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64388_solr [junit4] 2> 2366302 INFO (zkCallback-8784-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2366303 INFO (zkCallback-8791-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2366303 INFO (zkCallback-8798-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2366353 INFO (zkConnectionManagerCallback-8806-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2366356 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2366359 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64360/solr ready [junit4] 2> 2366360 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2366411 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64388.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2366467 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64388.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2366468 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64388.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2366470 INFO (jetty-launcher-8772-thread-2) [n:127.0.0.1:64388_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node2/. [junit4] 2> 2366597 INFO (zkConnectionManagerCallback-8809-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2366602 INFO (zkConnectionManagerCallback-8814-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2366603 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2366605 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64360/solr ready [junit4] 2> 2366618 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 2366632 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2366634 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1 [junit4] IGNOR/A 0.00s J1 | TestTlogReplica.testCreateDelete {seed=[D786EC9FD0CE31DD:CC961DD3263EC9ED]} [junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl="https://issues.apache.org/jira/browse/SOLR-12028";)) [junit4] IGNOR/A 0.00s J1 | TestTlogReplica.testCreateDelete {seed=[D786EC9FD0CE31DD:78C0A12F12FC02C1]} [junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl="https://issues.apache.org/jira/browse/SOLR-12028";)) [junit4] 2> 2366655 INFO (TEST-TestTlogReplica.testAddDocs-seed#[D786EC9FD0CE31DD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddDocs [junit4] 2> 2366658 INFO (qtp1564448736-28183) [n:127.0.0.1:64387_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_add_docs&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=3&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2366661 INFO (OverseerThreadFactory-12315-thread-1-processing-n:127.0.0.1:64387_solr) [n:127.0.0.1:64387_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_add_docs [junit4] 2> 2366769 WARN (OverseerThreadFactory-12315-thread-1-processing-n:127.0.0.1:64387_solr) [n:127.0.0.1:64387_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection tlog_replica_test_add_docs is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 2366776 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 2366794 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 2366802 INFO (OverseerStateUpdate-72143172801986564-127.0.0.1:64387_solr-n_0000000000) [n:127.0.0.1:64387_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:64388/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2366810 INFO (OverseerStateUpdate-72143172801986564-127.0.0.1:64387_solr-n_0000000000) [n:127.0.0.1:64387_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:64387/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2366815 INFO (OverseerStateUpdate-72143172801986564-127.0.0.1:64387_solr-n_0000000000) [n:127.0.0.1:64387_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:64387/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2367025 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 2367025 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2367026 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 2367026 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 2368044 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 2368044 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 2368044 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 2368059 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t2] Schema name=minimal [junit4] 2> 2368059 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t4] Schema name=minimal [junit4] 2> 2368059 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t1] Schema name=minimal [junit4] 2> 2368060 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2368060 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t2' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 2368061 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2368061 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2368061 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64387.solr.core.tlog_replica_test_add_docs.shard1.replica_t2' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2368061 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t4' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 2368061 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node1/tlog_replica_test_add_docs_shard1_replica_t2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node1/./tlog_replica_test_add_docs_shard1_replica_t2/data/] [junit4] 2> 2368061 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t1' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 2368062 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64388.solr.core.tlog_replica_test_add_docs.shard1.replica_t1' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2368062 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64387.solr.core.tlog_replica_test_add_docs.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5fb74f1c [junit4] 2> 2368062 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node2/tlog_replica_test_add_docs_shard1_replica_t1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node2/./tlog_replica_test_add_docs_shard1_replica_t1/data/] [junit4] 2> 2368062 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node1/tlog_replica_test_add_docs_shard1_replica_t4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-001/tempDir-001/node1/./tlog_replica_test_add_docs_shard1_replica_t4/data/] [junit4] 2> 2368116 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2368116 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2368118 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2368118 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2368121 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d0bd5fd[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 2368123 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2368124 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2368124 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2368125 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2368125 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2368127 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2368127 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2368127 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617647657382051840 [junit4] 2> 2368129 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2368129 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2368129 INFO (searcherExecutor-12328-thread-1-processing-n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t4 c:tlog_replica_test_add_docs s:shard1 r:core_node6) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@2d0bd5fd[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2368131 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2368131 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2368134 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a5ec5f0[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 2368135 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1cc155bb[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 2368137 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2368137 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0}, version=0} [junit4] 2> 2368137 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2368138 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2368153 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2368154 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617647657410363392 [junit4] 2> 2368153 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2368154 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2368154 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617647657410363392 [junit4] 2> 2368155 INFO (searcherExecutor-12327-thread-1-processing-n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t2 c:tlog_replica_test_add_docs s:shard1 r:core_node5) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@7a5ec5f0[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2368156 INFO (searcherExecutor-12329-thread-1-processing-n:127.0.0.1:64388_solr x:tlog_replica_test_add_docs_shard1_replica_t1 c:tlog_replica_test_add_docs s:shard1 r:core_node3) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@1cc155bb[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2368159 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node5=0}, version=1} [junit4] 2> 2368162 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2} [junit4] 2> 2368166 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2368166 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2368167 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/ [junit4] 2> 2368167 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t4 url=https://127.0.0.1:64387/solr START replicas=[https://127.0.0.1:64388/solr/tlog_replica_test_add_docs_shard1_replica_t1/, https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t2/] nUpdates=100 [junit4] 2> 2368168 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t4 url=https://127.0.0.1:64387/solr DONE. We have no versions. sync failed. [junit4] 2> 2368173 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2368173 INFO (qtp57018780-28191) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2368173 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2368173 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2368173 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2368174 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t4 stopping background replication from leader [junit4] 2> 2368180 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/ shard1 [junit4] 2> 2368348 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2368352 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=1326 [junit4] 2> 2368468 INFO (zkCallback-8784-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2369167 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t2 starting background replication from leader [junit4] 2> 2369167 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01 [junit4] 2> 2369168 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t1 starting background replication from leader [junit4] 2> 2369168 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01 [junit4] 2> 2369168 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 2369168 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2369168 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/ [junit4] 2> 2369168 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 2369168 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2369172 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2369172 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/ [junit4] 2> 2369172 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2369172 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2369172 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2369172 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2369172 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 2369174 INFO (qtp1564448736-28179) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2149 [junit4] 2> 2369175 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b6188ae[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 2369176 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2369176 INFO (searcherExecutor-12327-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@1b6188ae[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2369176 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2369177 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2369177 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2369177 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2369177 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 2369178 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f24d29b[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 2369178 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2152 [junit4] 2> 2369179 INFO (searcherExecutor-12329-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@6f24d29b[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2369182 INFO (qtp1564448736-28183) [n:127.0.0.1:64387_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 2369281 INFO (zkCallback-8798-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2369281 INFO (zkCallback-8784-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2370168 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 2370169 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c7f3880[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 2370170 INFO (searcherExecutor-12327-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@7c7f3880[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2370174 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 2370175 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bc6aa68[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 2370177 INFO (searcherExecutor-12329-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@1bc6aa68[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2370186 INFO (qtp1564448736-28183) [n:127.0.0.1:64387_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_add_docs&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=3&wt=javabin&version=2} status=0 QTime=3528 [junit4] 2> 2370196 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=1, core_node3=1, core_node5=1}, version=3} [junit4] 2> 2370199 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1617647659546312704)]} 0 0 [junit4] 2> 2370199 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:64387/solr/tlog_replica_test_add_docs_shard1_replica_t4/&wt=javabin&version=2}{add=[1 (1617647659546312704)]} 0 1 [junit4] 2> 2370199 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1617647659546312704)]} 0 8 [junit4] 2> 2370213 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:64388/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 2370214 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:64388/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 2370215 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617647659571478528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2370215 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@29ccfe20 commitCommandVersion:1617647659571478528 [junit4] 2> 2370234 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@7189ff7a[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 2370234 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2370236 INFO (searcherExecutor-12328-thread-1-processing-n:127.0.0.1:64387_solr x:tlog_replica_test_add_docs_shard1_replica_t4 c:tlog_replica_test_add_docs s:shard1 r:core_node6) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@7189ff7a[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C1)))} [junit4] 2> 2370236 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:64388/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 21 [junit4] 2> 2370237 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_add_docs:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 29 [junit4] 2> 2370242 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 2370250 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370356 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370458 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370566 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370669 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370670 INFO (OverseerCollectionConfigSetProcessor-72143172801986564-127.0.0.1:64387_solr-n_0000000000) [n:127.0.0.1:64387_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2370771 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370882 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2370993 INFO (qtp57018780-28192) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2371104 INFO (qtp57018780-28188) [n:127.0.0.1:64388_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 2371179 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2371179 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2371179 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 2371179 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 2371179 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1542709025928 [junit4] 2> 2371179 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1542709025928 [junit4] 2> 2371179 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2371179 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2371179 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2371179 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2371179 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2371179 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 2371181 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 2371181 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 13 [junit4] 2> 2371181 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 2371181 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 13 [junit4] 2> 2371182 INFO (indexFetcher-12343-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6e69a962 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@67ea1db3) [junit4] 2> 2371182 INFO (indexFetcher-12345-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@3b38642e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@297548bc) [junit4] 2> 2371182 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371183 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371185 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371185 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371186 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Memory_1.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371186 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Memory_1.ram&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371187 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_1.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371187 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_1.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371188 INFO (qtp1564448736-28181) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371188 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371189 INFO (qtp1564448736-28185) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371189 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 2371190 INFO (qtp1564448736-28184) [n:127.0.0.1:64387_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add [...truncated too long message...] Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 329807 INFO (jetty-closer-266-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72143332475142169-127.0.0.1:57094_solr-n_0000000005) closing [junit4] 2> 329808 INFO (OverseerStateUpdate-72143332475142169-127.0.0.1:57094_solr-n_0000000005) [n:127.0.0.1:57094_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57094_solr [junit4] 2> 329809 WARN (OverseerAutoScalingTriggerThread-72143332475142169-127.0.0.1:57094_solr-n_0000000005) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 329811 INFO (jetty-closer-266-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57095_solr [junit4] 2> 329813 INFO (jetty-closer-266-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 329815 INFO (zkCallback-393-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 329815 INFO (zkCallback-386-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 329815 INFO (zkCallback-386-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57095_solr [junit4] 2> 331324 WARN (zkCallback-368-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 331325 INFO (jetty-closer-266-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6001acc4{/solr,null,UNAVAILABLE} [junit4] 2> 331325 INFO (jetty-closer-266-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 337125 ERROR (recoveryExecutor-383-thread-1-processing-n:127.0.0.1:57095_solr x:tlog_replica_test_recovery_shard1_replica_t2 c:tlog_replica_test_recovery s:shard1 r:core_node4) [n:127.0.0.1:57095_solr c:tlog_replica_test_recovery s:shard1 r:core_node4 x:tlog_replica_test_recovery_shard1_replica_t2] o.a.s.c.RecoveryStrategy SolrCore not found - cannot recover:tlog_replica_test_recovery_shard1_replica_t2 [junit4] 2> 337129 INFO (jetty-closer-266-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4eb59965{/solr,null,UNAVAILABLE} [junit4] 2> 337130 INFO (jetty-closer-266-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 337131 ERROR (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 337131 INFO (SUITE-TestTlogReplica-seed#[D786EC9FD0CE31DD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57092 57092 [junit4] 2> 337294 INFO (Thread-152) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57092 57092 [junit4] 2> 337477 WARN (Thread-152) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 32 /solr/configs/conf [junit4] 2> 31 /solr/collections/tlog_replica_test_create_delete/terms/shard2 [junit4] 2> 31 /solr/collections/tlog_replica_test_create_delete/terms/shard1 [junit4] 2> 18 /solr/collections/tlog_replica_test_recovery/terms/shard1 [junit4] 2> 18 /solr/collections/tlog_replica_test_remove_leader/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_kill_leader/terms/shard1 [junit4] 2> 15 /solr/aliases.json [junit4] 2> 15 /solr/collections/tlog_replica_test_basic_leader_election/terms/shard1 [junit4] 2> 15 /solr/collections/tlog_replica_test_kill_tlog_replica/terms/shard1 [junit4] 2> 13 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_add_docs/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_real_time_get/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_only_leader_indexes/terms/shard1 [junit4] 2> 9 /solr/collections/tlog_replica_test_delete_by_id/terms/shard1 [junit4] 2> 8 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard2 [junit4] 2> 7 /solr/security.json [junit4] 2> 7 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 23 /solr/collections/tlog_replica_test_create_delete/state.json [junit4] 2> 15 /solr/clusterprops.json [junit4] 2> 15 /solr/clusterstate.json [junit4] 2> 13 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 9 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 5 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 5 /solr/overseer_elect/election/72143332475142150-127.0.0.1:57095_solr-n_0000000000 [junit4] 2> 4 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72143332475142150-core_node7-n_0000000001 [junit4] 2> 4 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72143332475142150-core_node13-n_0000000001 [junit4] 2> 3 /solr/collections/tlog_replica_test_add_remove_tlog_replica/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_real_time_get/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_only_leader_indexes/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_delete_by_id/state.json [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72143332475142148-core_node9-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/72143332475142169-core_node3-n_0000000000 [junit4] 2> 2 /solr/autoscaling.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 15 /solr/live_nodes [junit4] 2> 15 /solr/collections [junit4] 2> 2 /solr/overseer/queue [junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 2 /solr/overseer/queue-work [junit4] 2> 2 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_D786EC9FD0CE31DD-003 [junit4] 2> Nov 20, 2018 10:59:24 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {foo=PostingsFormat(name=Memory), title_s=PostingsFormat(name=Memory), id=PostingsFormat(name=Memory), foo_s=PostingsFormat(name=Memory)}, docValues:{_version_=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Lucene70), inplace_updatable_int=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=447, maxMBSortInHeap=7.817612013645664, sim=RandomSimilarity(queryNorm=false): {}, locale=gl-ES, timezone=Asia/Novosibirsk [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=98264584,total=284164096 [junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplica, TestTlogReplica, TestTlogReplica] [junit4] Completed [5/5 (5!)] on J0 in 107.45s, 13 tests, 1 failure <<< FAILURES! [...truncated 14 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1567: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/lucene/common-build.xml:1092: There were test failures: 5 suites, 65 tests, 5 failures [seed: D786EC9FD0CE31DD] Total time: 5 minutes 41 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 5/5 failed: org.apache.solr.cloud.TestTlogReplica [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=a4e95f39be9d7f107b9027eb950775dc59406932, workspace=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX [WARNINGS] Computing warning deltas based on reference build #942 Recording test results Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
