Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/887/ Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseSerialGC
5 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:50395/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:50395/solr at __randomizedtesting.SeedInfo.seed([968C6C528E719FD7:577C15FEA3215570]: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:901) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:566) 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:50740/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:50740/solr at __randomizedtesting.SeedInfo.seed([968C6C528E719FD7:577C15FEA3215570]: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:901) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:566) 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:50735/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:50735/solr at __randomizedtesting.SeedInfo.seed([968C6C528E719FD7:577C15FEA3215570]: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:901) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:566) 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:51071/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:51071/solr at __randomizedtesting.SeedInfo.seed([968C6C528E719FD7:577C15FEA3215570]: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:901) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:566) 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:60029/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:60029/solr at __randomizedtesting.SeedInfo.seed([968C6C528E719FD7:577C15FEA3215570]: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:901) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:566) 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 14082 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_968C6C528E719FD7-001/init-core-data-001 [junit4] 2> 2567120 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2567121 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-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> 2567122 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2567122 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-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_968C6C528E719FD7-001/tempDir-001 [junit4] 2> 2567122 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2567122 INFO (Thread-4098) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2567122 INFO (Thread-4098) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2567135 ERROR (Thread-4098) [ ] 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> 2567228 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:60027 [junit4] 2> 2567264 INFO (zkConnectionManagerCallback-11062-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567272 INFO (jetty-launcher-11059-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> 2567272 INFO (jetty-launcher-11059-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> 2567274 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2567274 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2567274 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2567274 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2567274 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3fa5c16f{/solr,null,AVAILABLE} [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@628b830c{/solr,null,AVAILABLE} [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e34c4c{SSL,[ssl, http/1.1]}{127.0.0.1:60030} [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@394830b1{SSL,[ssl, http/1.1]}{127.0.0.1:60029} [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-2) [ ] o.e.j.s.Server Started @2567350ms [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-1) [ ] o.e.j.s.Server Started @2567350ms [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60030} [junit4] 2> 2567275 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60029} [junit4] 2> 2567276 ERROR (jetty-launcher-11059-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2567276 ERROR (jetty-launcher-11059-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.6.0 [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.6.0 [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-16T14:44:24.316553Z [junit4] 2> 2567276 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-16T14:44:24.316561Z [junit4] 2> 2567278 INFO (zkConnectionManagerCallback-11065-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567278 INFO (zkConnectionManagerCallback-11066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567279 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2567279 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2567334 INFO (jetty-launcher-11059-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60027/solr [junit4] 2> 2567335 INFO (zkConnectionManagerCallback-11070-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567338 INFO (zkConnectionManagerCallback-11072-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567417 INFO (jetty-launcher-11059-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60027/solr [junit4] 2> 2567418 INFO (zkConnectionManagerCallback-11078-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567421 INFO (zkConnectionManagerCallback-11080-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567440 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60030_solr [junit4] 2> 2567442 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.c.Overseer Overseer (id=72187009188560902-127.0.0.1:60030_solr-n_0000000000) starting [junit4] 2> 2567452 INFO (zkConnectionManagerCallback-11087-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567455 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60027/solr ready [junit4] 2> 2567456 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:60030_solr [junit4] 2> 2567456 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60030_solr [junit4] 2> 2567457 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2567459 INFO (zkCallback-11086-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2567475 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2567489 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2567496 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60030.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567497 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2567497 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60029_solr [junit4] 2> 2567498 INFO (zkCallback-11079-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2567498 INFO (zkCallback-11086-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2567498 INFO (zkCallback-11071-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2567506 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60030.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567507 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60030.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567508 INFO (jetty-launcher-11059-thread-2) [n:127.0.0.1:60030_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_968C6C528E719FD7-001/tempDir-001/node2/. [junit4] 2> 2567518 INFO (zkConnectionManagerCallback-11093-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567520 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2567521 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60027/solr ready [junit4] 2> 2567522 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2567545 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60029.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567553 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60029.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567553 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60029.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2567554 INFO (jetty-launcher-11059-thread-1) [n:127.0.0.1:60029_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_968C6C528E719FD7-001/tempDir-001/node1/. [junit4] 2> 2567573 INFO (zkConnectionManagerCallback-11096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567577 INFO (zkConnectionManagerCallback-11101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2567579 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2567580 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60027/solr ready [junit4] 2> 2567586 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 2567600 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_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> 2567602 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_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=2 [junit4] 2> 2567623 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[968C6C528E719FD7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRealTimeGet [junit4] 2> 2567624 INFO (qtp1358363372-30112) [n:127.0.0.1:60030_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_real_time_get&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2567627 INFO (OverseerThreadFactory-10481-thread-1-processing-n:127.0.0.1:60030_solr) [n:127.0.0.1:60030_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_real_time_get [junit4] 2> 2567734 WARN (OverseerThreadFactory-10481-thread-1-processing-n:127.0.0.1:60030_solr) [n:127.0.0.1:60030_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 4 on collection tlog_replica_test_real_time_get 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> 2567740 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_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> 2567755 INFO (qtp1916598279-30115) [n:127.0.0.1:60029_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> 2567764 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:60029/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2567769 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:60030/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2567773 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:60030/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2567777 INFO (OverseerStateUpdate-72187009188560902-127.0.0.1:60030_solr-n_0000000000) [n:127.0.0.1:60030_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_real_time_get", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_real_time_get_shard1_replica_t6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:60029/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2567991 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 2567991 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t6&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 2567991 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_n2&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2567992 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2567991 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_n1&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2569019 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 2569020 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 2569021 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 2569039 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 2569041 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_n2] Schema name=minimal [junit4] 2> 2569041 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_t6] Schema name=minimal [junit4] 2> 2569043 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2569043 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2569043 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_t6' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 2569043 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_n2' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 2569044 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60030.solr.core.tlog_replica_test_real_time_get.shard1.replica_n2' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2569044 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60029.solr.core.tlog_replica_test_real_time_get.shard1.replica_t6' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_t6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2569044 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_n2/data/] [junit4] 2> 2569044 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_t6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node1/tlog_replica_test_real_time_get_shard1_replica_t6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_t6/data/] [junit4] 2> 2569045 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_t4] Schema name=minimal [junit4] 2> 2569046 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2569046 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_t4' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 2569046 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60030.solr.core.tlog_replica_test_real_time_get.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2569047 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_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_968C6C528E719FD7-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_t4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_t4/data/] [junit4] 2> 2569048 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_n1] Schema name=minimal [junit4] 2> 2569049 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2569049 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_n1' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 2569049 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60029.solr.core.tlog_replica_test_real_time_get.shard1.replica_n1' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@299b7e9f [junit4] 2> 2569049 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node1/tlog_replica_test_real_time_get_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_n1/data/] [junit4] 2> 2569138 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2569138 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2569139 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2569139 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2569140 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2569140 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2569141 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2569141 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2569142 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bc3608[tlog_replica_test_real_time_get_shard1_replica_n2] main] [junit4] 2> 2569143 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.SolrIndexSearcher Opening [Searcher@637dbf6b[tlog_replica_test_real_time_get_shard1_replica_t6] main] [junit4] 2> 2569144 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2569144 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2569144 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2569144 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2569145 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2569145 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2569145 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614493585176002560 [junit4] 2> 2569146 INFO (searcherExecutor-10491-thread-1-processing-n:127.0.0.1:60030_solr x:tlog_replica_test_real_time_get_shard1_replica_n2 c:tlog_replica_test_real_time_get s:shard1 r:core_node5) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n2] Registered new searcher Searcher@3bc3608[tlog_replica_test_real_time_get_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2569146 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614493585177051136 [junit4] 2> 2569146 INFO (searcherExecutor-10490-thread-1-processing-n:127.0.0.1:60029_solr x:tlog_replica_test_real_time_get_shard1_replica_t6 c:tlog_replica_test_real_time_get s:shard1 r:core_node8) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t6] Registered new searcher Searcher@637dbf6b[tlog_replica_test_real_time_get_shard1_replica_t6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2569153 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node8=0}, version=0} [junit4] 2> 2569163 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=4 found=1 timeoutin=9998ms [junit4] 2> 2569162 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node8=0, core_node5=0}, version=1} [junit4] 2> 2569207 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2569207 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2569208 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2569208 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2569210 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@379f3e75[tlog_replica_test_real_time_get_shard1_replica_n1] main] [junit4] 2> 2569213 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2569214 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2569215 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2569215 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2569219 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2569219 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2569225 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2569225 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@44b54302[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 2569251 INFO (searcherExecutor-10493-thread-1-processing-n:127.0.0.1:60029_solr x:tlog_replica_test_real_time_get_shard1_replica_n1 c:tlog_replica_test_real_time_get s:shard1 r:core_node3) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n1] Registered new searcher Searcher@379f3e75[tlog_replica_test_real_time_get_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2569251 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614493585287151616 [junit4] 2> 2569252 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2569252 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2569253 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2569253 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614493585289248768 [junit4] 2> 2569254 INFO (searcherExecutor-10492-thread-1-processing-n:127.0.0.1:60030_solr x:tlog_replica_test_real_time_get_shard1_replica_t4 c:tlog_replica_test_real_time_get s:shard1 r:core_node7) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] Registered new searcher Searcher@44b54302[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2569257 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node7=0, core_node8=0, core_node5=0}, version=2} [junit4] 2> 2569257 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 2569260 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node7=0, core_node8=0, core_node3=0, core_node5=0}, version=3} [junit4] 2> 2569675 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2569675 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2569675 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/ [junit4] 2> 2569676 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_t6 url=https://127.0.0.1:60029/solr START replicas=[https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/, https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/, https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_t4/] nUpdates=100 [junit4] 2> 2569676 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_t6 url=https://127.0.0.1:60029/solr DONE. We have no versions. sync failed. [junit4] 2> 2569679 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2569679 INFO (qtp1358363372-30122) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2569680 INFO (qtp1358363372-30108) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2569680 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2569680 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] 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> 2569680 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 2569681 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t6 stopping background replication from leader [junit4] 2> 2569690 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/ shard1 [junit4] 2> 2569797 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2569799 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t6&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=1807 [junit4] 2> 2569910 INFO (zkCallback-11071-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2570181 INFO (qtp1358363372-30110) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] 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_real_time_get_shard1_replica_n2&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2190 [junit4] 2> 2570276 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t4 starting background replication from leader [junit4] 2> 2570276 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 2570277 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 2570277 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2570279 INFO (qtp1916598279-30118) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] 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_real_time_get_shard1_replica_n1&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2287 [junit4] 2> 2570280 INFO (qtp1358363372-30121) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=tlog_replica_test_real_time_get_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2289 [junit4] 2> 2570283 INFO (qtp1358363372-30112) [n:127.0.0.1:60030_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> 2570340 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/ [junit4] 2> 2570341 INFO (qtp1916598279-30115) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 2570341 INFO (indexFetcher-10511-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7fc4546a[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 2570343 INFO (searcherExecutor-10492-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] Registered new searcher Searcher@7fc4546a[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2570391 INFO (zkCallback-11079-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2570391 INFO (zkCallback-11071-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_real_time_get/state.json] for collection [tlog_replica_test_real_time_get] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2571284 INFO (qtp1358363372-30112) [n:127.0.0.1:60030_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_real_time_get&nrtReplicas=2&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2} status=0 QTime=3659 [junit4] 2> 2571298 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node7=1, core_node8=1, core_node3=1, core_node5=1}, version=4} [junit4] 2> 2571301 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[0 (1614493587430440960)]} 0 1 [junit4] 2> 2571301 INFO (qtp1916598279-30114) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[0 (1614493587430440960)]} 0 1 [junit4] 2> 2571301 INFO (qtp1358363372-30108) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[0 (1614493587430440960)]} 0 1 [junit4] 2> 2571301 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2}{add=[0 (1614493587430440960)]} 0 6 [junit4] 2> 2571301 INFO (qtp1916598279-30115) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 8 [junit4] 2> 2571306 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:6&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571306 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571307 INFO (qtp1358363372-30122) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571308 INFO (qtp1358363372-30108) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 2571308 INFO (qtp1358363372-30122) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1539701068347&ids=0&isShard=true&shard.url=https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 2571309 INFO (qtp1358363372-30108) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 2571309 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 2571312 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1539701068349&ids=0&isShard=true&shard.url=https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 2571312 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 2571316 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[1 (1614493587451412480)]} 0 0 [junit4] 2> 2571317 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[1 (1614493587451412480)]} 0 1 [junit4] 2> 2571318 INFO (qtp1358363372-30122) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[1 (1614493587451412480)]} 0 1 [junit4] 2> 2571318 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/&wt=javabin&version=2}{add=[1 (1614493587451412480)]} 0 3 [junit4] 2> 2571318 INFO (qtp1358363372-30108) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 5 [junit4] 2> 2571319 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&_stateVer_=tlog_replica_test_real_time_get:6&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571320 INFO (qtp1916598279-30114) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571320 INFO (qtp1358363372-30122) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2571321 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/ [junit4] 2> 2571322 INFO (qtp1916598279-30117) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1539701068360&ids=1&isShard=true&shard.url=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 2571322 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node7 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t4] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 2571323 INFO (qtp1916598279-30114) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/ [junit4] 2> 2571325 INFO (qtp1916598279-30116) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node3 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1539701068363&ids=1&isShard=true&shard.url=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:60030/solr/tlog_replica_test_real_time_get_shard1_replica_n2/|https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 2571325 INFO (qtp1916598279-30114) [n:127.0.0.1:60029_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node8 x:tlog_replica_test_real_time_get_shard1_replica_t6] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_t6] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 2571328 INFO (qtp1358363372-30106) [n:127.0.0.1:60030_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:60029/solr/tlog_replica_test_real_time_get_shard1_replica_t6/&wt=javabin&version=2}{add=[2 (1614493587463995392)]} 0 0 [junit4] 2> 2571329 INFO (qtp1916598279-30114) [n:127.0.0.1:60029_sol [...truncated too long message...] 2> 345407 INFO (jetty-closer-266-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ded9398: rootName = solr_51072, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@6197c8e5 [junit4] 2> 345408 INFO (jetty-closer-266-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:51071_solr [junit4] 2> 345410 INFO (jetty-closer-266-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 345412 INFO (zkCallback-314-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 345412 INFO (zkCallback-321-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 345413 INFO (zkCallback-384-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 345413 INFO (zkCallback-391-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 345415 INFO (jetty-closer-266-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:51072_solr [junit4] 2> 345418 INFO (jetty-closer-266-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 345420 INFO (jetty-closer-266-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6d85b18f{/solr,null,UNAVAILABLE} [junit4] 2> 345420 INFO (zkCallback-321-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 345420 INFO (zkCallback-314-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 345420 INFO (jetty-closer-266-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 345424 INFO (jetty-closer-266-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72187157904490509-127.0.0.1:51072_solr-n_0000000002) closing [junit4] 2> 345424 INFO (OverseerStateUpdate-72187157904490509-127.0.0.1:51072_solr-n_0000000002) [n:127.0.0.1:51072_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:51072_solr [junit4] 2> 345425 WARN (OverseerAutoScalingTriggerThread-72187157904490509-127.0.0.1:51072_solr-n_0000000002) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 345428 INFO (jetty-closer-266-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19dbc48e{/solr,null,UNAVAILABLE} [junit4] 2> 345428 INFO (jetty-closer-266-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 345429 ERROR (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-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> 345429 INFO (SUITE-TestTlogReplica-seed#[968C6C528E719FD7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51069 51069 [junit4] 2> 345444 INFO (Thread-155) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51069 51069 [junit4] 2> 345703 WARN (Thread-155) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 33 /solr/collections/tlog_replica_test_create_delete/terms/shard1 [junit4] 2> 32 /solr/configs/conf [junit4] 2> 31 /solr/collections/tlog_replica_test_create_delete/terms/shard2 [junit4] 2> 19 /solr/collections/tlog_replica_test_remove_leader/terms/shard1 [junit4] 2> 18 /solr/collections/tlog_replica_test_recovery/terms/shard1 [junit4] 2> 18 /solr/collections/tlog_replica_test_real_time_get/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_kill_leader/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_basic_leader_election/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_kill_tlog_replica/terms/shard1 [junit4] 2> 15 /solr/aliases.json [junit4] 2> 14 /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_delete_by_id/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_only_leader_indexes/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/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 15 /solr/clusterstate.json [junit4] 2> 15 /solr/clusterprops.json [junit4] 2> 11 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 7 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 5 /solr/collections/tlog_replica_test_real_time_get/state.json [junit4] 2> 4 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 4 /solr/overseer_elect/election/72187157904490509-127.0.0.1:51072_solr-n_0000000002 [junit4] 2> 3 /solr/autoscaling.json [junit4] 2> 3 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72187157904490509-core_node7-n_0000000000 [junit4] 2> 3 /solr/collections/tlog_replica_test_add_remove_tlog_replica/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_kill_tlog_replica/leader_elect/shard1/election/72187157904490509-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/72187157904490509-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72187157904490517-core_node3-n_0000000000 [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> 3 /solr/overseer/queue [junit4] 2> 3 /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/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/J1/temp/solr.cloud.TestTlogReplica_968C6C528E719FD7-003 [junit4] 2> Oct 16, 2018 3:23:59 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=ee, timezone=America/Indiana/Tell_City [junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9 (64-bit)/cpus=3,threads=1,free=57819608,total=319905792 [junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplica, TestTlogReplica, TestTlogReplica] [junit4] Completed [5/5 (5!)] on J1 in 106.40s, 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: 968C6C528E719FD7] Total time: 5 minutes 50 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=7a2504e18c1508ed5b44ef5692d216b19f9d6a6b, workspace=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX [WARNINGS] Computing warning deltas based on reference build #886 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org