Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/23244/ Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -XX:+UseSerialGC
4 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:39999/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:39999/solr at __randomizedtesting.SeedInfo.seed([45C46B08F8C0E291:843412A4D5902836]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:37153/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:37153/solr at __randomizedtesting.SeedInfo.seed([45C46B08F8C0E291:843412A4D5902836]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:33127/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:33127/solr at __randomizedtesting.SeedInfo.seed([45C46B08F8C0E291:843412A4D5902836]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:34891/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:34891/solr at __randomizedtesting.SeedInfo.seed([45C46B08F8C0E291:843412A4D5902836]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.cloud.TestTlogReplica.checkRTG(TestTlogReplica.java:902) at org.apache.solr.cloud.TestTlogReplica.testRecovery(TestTlogReplica.java:567) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 14320 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/init-core-data-001 [junit4] 2> 1615155 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1615156 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-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) [junit4] 2> 1615156 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1615157 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001 [junit4] 2> 1615157 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1615158 INFO (Thread-4223) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1615158 INFO (Thread-4223) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1615163 ERROR (Thread-4223) [ ] 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> 1615258 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44365 [junit4] 2> 1615259 INFO (zkConnectionManagerCallback-9502-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615262 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11 [junit4] 2> 1615262 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11 [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f698409{/solr,null,AVAILABLE} [junit4] 2> 1615270 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@48c1438e{/solr,null,AVAILABLE} [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@315e3ddc{SSL,[ssl, http/1.1]}{127.0.0.1:۳۳۵۳۳} [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.e.j.s.Server Started @۱۶۱۵۳۰۲ms [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33533} [junit4] 2> 1615272 ERROR (jetty-launcher-9499-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1615272 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-21T13:25:35.939019Z [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5f3c6cea{SSL,[ssl, http/1.1]}{127.0.0.1:۳۴۸۹۱} [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.e.j.s.Server Started @۱۶۱۵۳۰۳ms [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34891} [junit4] 2> 1615273 ERROR (jetty-launcher-9499-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1615273 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-21T13:25:35.939852Z [junit4] 2> 1615286 INFO (zkConnectionManagerCallback-9506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615287 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1615317 INFO (zkConnectionManagerCallback-9504-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615326 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1615455 INFO (jetty-launcher-9499-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44365/solr [junit4] 2> 1615463 INFO (jetty-launcher-9499-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44365/solr [junit4] 2> 1615477 INFO (zkConnectionManagerCallback-9510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615493 INFO (zkConnectionManagerCallback-9514-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615505 INFO (zkConnectionManagerCallback-9516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615523 INFO (zkConnectionManagerCallback-9518-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615538 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33533_solr [junit4] 2> 1615539 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.c.Overseer Overseer (id=72062253593133062-127.0.0.1:33533_solr-n_0000000000) starting [junit4] 2> 1615586 INFO (zkConnectionManagerCallback-9527-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615588 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44365/solr ready [junit4] 2> 1615591 INFO (OverseerStateUpdate-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [n:127.0.0.1:33533_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33533_solr [junit4] 2> 1615603 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33533_solr [junit4] 2> 1615603 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 1615612 INFO (zkCallback-9517-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1615612 INFO (zkCallback-9526-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1615614 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1615614 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34891_solr [junit4] 2> 1615614 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1615614 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1615614 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1615615 INFO (zkCallback-9515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1615621 INFO (zkCallback-9517-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1615622 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1615627 INFO (zkCallback-9515-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1615630 INFO (zkCallback-9526-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1615631 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:33533_solr, 127.0.0.1:34891_solr] [junit4] 2> 1615641 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615649 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615649 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615650 INFO (jetty-launcher-9499-thread-1) [n:127.0.0.1:33533_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node1/. [junit4] 2> 1615650 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1615650 DEBUG (OverseerAutoScalingTriggerThread-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1615650 DEBUG (ScheduledTrigger-8365-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1615669 INFO (zkConnectionManagerCallback-9533-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615672 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1615675 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44365/solr ready [junit4] 2> 1615689 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1615702 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34891.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615710 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34891.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615710 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34891.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1615711 INFO (jetty-launcher-9499-thread-2) [n:127.0.0.1:34891_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node2/. [junit4] 2> 1615730 INFO (zkConnectionManagerCallback-9536-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615732 INFO (zkConnectionManagerCallback-9541-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1615733 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1615734 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44365/solr ready [junit4] 2> 1615736 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 1615752 INFO (qtp1002186030-26626) [n:127.0.0.1:34891_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> 1615755 INFO (qtp1002186030-26626) [n:127.0.0.1:34891_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> 1615792 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[45C46B08F8C0E291]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRealTimeGet [junit4] 2> 1615798 INFO (qtp1002186030-26622) [n:127.0.0.1:34891_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=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1615799 INFO (OverseerThreadFactory-8368-thread-1-processing-n:127.0.0.1:33533_solr) [n:127.0.0.1:33533_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_real_time_get [junit4] 2> 1615906 WARN (OverseerThreadFactory-8368-thread-1-processing-n:127.0.0.1:33533_solr) [n:127.0.0.1:33533_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 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> 1615911 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_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> 1615922 INFO (qtp421852300-26624) [n:127.0.0.1:33533_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> 1615924 INFO (OverseerStateUpdate-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [n:127.0.0.1:33533_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:33533/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1615942 INFO (OverseerStateUpdate-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [n:127.0.0.1:33533_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_n3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:34891/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1615942 INFO (OverseerStateUpdate-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [n:127.0.0.1:33533_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:34891/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1616178 INFO (qtp421852300-26619) [n:127.0.0.1:33533_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_node2&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> 1616178 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1616178 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] 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_n3&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1616196 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_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_node6&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> 1616652 DEBUG (ScheduledTrigger-8365-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1617199 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1617202 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1617205 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.s.IndexSchema [tlog_replica_test_real_time_get_shard1_replica_n3] Schema name=minimal [junit4] 2> 1617207 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1617207 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_real_time_get_shard1_replica_n3' using configuration from collection tlog_replica_test_real_time_get, trusted=true [junit4] 2> 1617207 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617207 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34891.solr.core.tlog_replica_test_real_time_get.shard1.replica_n3' (registry 'solr.core.tlog_replica_test_real_time_get.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@223afdfa [junit4] 2> 1617207 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SolrCore [[tlog_replica_test_real_time_get_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_n3/data/] [junit4] 2> 1617209 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617209 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617209 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.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@223afdfa [junit4] 2> 1617209 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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 [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node1/tlog_replica_test_real_time_get_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node1/./tlog_replica_test_real_time_get_shard1_replica_n1/data/] [junit4] 2> 1617214 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1617232 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617233 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617233 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617233 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34891.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@223afdfa [junit4] 2> 1617234 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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 [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node2/tlog_replica_test_real_time_get_shard1_replica_t4], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-001/tempDir-001/node2/./tlog_replica_test_real_time_get_shard1_replica_t4/data/] [junit4] 2> 1617257 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1617257 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1617258 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1617258 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1617259 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@9cc0e58[tlog_replica_test_real_time_get_shard1_replica_n3] main] [junit4] 2> 1617259 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1617259 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1617259 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1617260 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617750118027493376 [junit4] 2> 1617260 INFO (searcherExecutor-8376-thread-1-processing-n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3 c:tlog_replica_test_real_time_get s:shard1 r:core_node5) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n3] Registered new searcher Searcher@9cc0e58[tlog_replica_test_real_time_get_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1617263 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node5=0}, version=0} [junit4] 2> 1617264 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoutin=9999ms [junit4] 2> 1617291 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617291 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617292 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1617292 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1617293 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13e99e77[tlog_replica_test_real_time_get_shard1_replica_n1] main] [junit4] 2> 1617294 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1617294 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1617294 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1617295 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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 1617750118064193536 [junit4] 2> 1617295 INFO (searcherExecutor-8377-thread-1-processing-n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1 c:tlog_replica_test_real_time_get s:shard1 r:core_node2) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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@13e99e77[tlog_replica_test_real_time_get_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1617298 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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_node2=0, core_node5=0}, version=1} [junit4] 2> 1617299 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617299 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617300 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1617300 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1617301 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ac5d02f[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 1617302 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1617302 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1617302 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1617302 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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 1617750118071533568 [junit4] 2> 1617303 INFO (searcherExecutor-8378-thread-1-processing-n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4 c:tlog_replica_test_real_time_get s:shard1 r:core_node6) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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@6ac5d02f[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1617304 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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_node2=0, core_node6=0, core_node5=0}, version=2} [junit4] 2> 1617652 DEBUG (ScheduledTrigger-8365-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1617765 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1617765 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1617765 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ [junit4] 2> 1617766 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_n3 url=https://127.0.0.1:34891/solr START replicas=[https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/, https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_t4/] nUpdates=100 [junit4] 2> 1617766 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_real_time_get_shard1_replica_n3 url=https://127.0.0.1:34891/solr DONE. We have no versions. sync failed. [junit4] 2> 1617769 INFO (qtp1002186030-26626) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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=1 [junit4] 2> 1617769 INFO (qtp421852300-26617) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=2 [junit4] 2> 1617769 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 1617769 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] 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> 1617771 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ shard1 [junit4] 2> 1617872 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1617873 INFO (qtp1002186030-26618) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] 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_n3&action=CREATE&numShards=1&collection=tlog_replica_test_real_time_get&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1695 [junit4] 2> 1617974 INFO (zkCallback-9515-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> 1618300 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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_node2&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=2122 [junit4] 2> 1618306 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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> 1618306 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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:01 [junit4] 2> 1618307 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 1618307 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1618308 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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_node6&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=2111 [junit4] 2> 1618309 INFO (qtp1002186030-26622) [n:127.0.0.1:34891_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> 1618408 INFO (zkCallback-9517-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> 1618408 INFO (zkCallback-9515-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> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ [junit4] 2> 1618478 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 1618478 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 1618479 INFO (indexFetcher-8392-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a9df9fc[tlog_replica_test_real_time_get_shard1_replica_t4] main] [junit4] 2> 1618480 INFO (searcherExecutor-8378-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] Registered new searcher Searcher@7a9df9fc[tlog_replica_test_real_time_get_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1618652 DEBUG (ScheduledTrigger-8365-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1619309 INFO (qtp1002186030-26622) [n:127.0.0.1:34891_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=1&wt=javabin&version=2} status=0 QTime=3511 [junit4] 2> 1619317 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={core_node6=1, core_node2=1, core_node5=1}, version=3} [junit4] 2> 1619328 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[0 (1617750120182317056)]} 0 10 [junit4] 2> 1619329 INFO (qtp421852300-26623) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[0 (1617750120182317056)]} 0 10 [junit4] 2> 1619329 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2}{add=[0 (1617750120182317056)]} 0 13 [junit4] 2> 1619329 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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 15 [junit4] 2> 1619330 INFO (qtp421852300-26613) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1619330 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1619331 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/get params={qt=/get&ids=0&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619331 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/|https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/ [junit4] 2> 1619334 INFO (qtp1002186030-26616) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&shards.purpose=1&NOW=1542806739997&ids=0&isShard=true&shard.url=https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/|https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 1619334 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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=2 [junit4] 2> 1619335 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[1 (1617750120202240000)]} 0 0 [junit4] 2> 1619335 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[1 (1617750120202240000)]} 0 0 [junit4] 2> 1619335 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1617750120202240000)]} 0 1 [junit4] 2> 1619336 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] 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> 1619336 INFO (qtp421852300-26613) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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> 1619337 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/get params={qt=/get&ids=1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619337 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ [junit4] 2> 1619339 INFO (qtp421852300-26624) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=1542806740003&ids=1&isShard=true&shard.url=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 1619339 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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=2 [junit4] 2> 1619342 INFO (qtp1002186030-26626) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[2 (1617750120208531456)]} 0 0 [junit4] 2> 1619342 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2}{add=[2 (1617750120208531456)]} 0 1 [junit4] 2> 1619343 INFO (qtp1002186030-26616) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_t4/&wt=javabin&version=2}{add=[2 (1617750120208531456)]} 0 2 [junit4] 2> 1619343 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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={wt=javabin&version=2}{add=[2]} 0 2 [junit4] 2> 1619343 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619344 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619344 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/get params={qt=/get&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619344 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ [junit4] 2> 1619345 INFO (qtp421852300-26613) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=1542806740010&ids=2&isShard=true&shard.url=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 1619345 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619346 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619346 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node5 x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.S.Request [tlog_replica_test_real_time_get_shard1_replica_n3] webapp=/solr path=/get params={qt=/get&ids=0&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619347 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/ [junit4] 2> 1619347 INFO (qtp421852300-26619) [n:127.0.0.1:33533_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node2 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=1542806740013&ids=0,1,2&isShard=true&shard.url=https://127.0.0.1:33533/solr/tlog_replica_test_real_time_get_shard1_replica_n1/|https://127.0.0.1:34891/solr/tlog_replica_test_real_time_get_shard1_replica_n3/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0 [junit4] 2> 1619347 INFO (qtp1002186030-26625) [n:127.0.0.1:34891_solr c:tlog_replica_test_real_time_get s:shard1 r:core_node6 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&ids=1&ids=2&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1619348 INFO (TEST-TestTlogReplica.testRealTimeGet-seed#[45C46B08F8C0E291]) [ ] o.a.s.c.TestTlogReplica tearDown deleting collection [junit4] 2> 1619348 INFO (qtp1002186030-26689) [n:127.0.0.1:34891_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=tlog_replica_test_real_time_get&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1619349 INFO (OverseerCollectionConfigSetProcessor-72062253593133062-127.0.0.1:33533_solr-n_0000000000) [n:127.0.0.1:33533_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1619349 INFO (OverseerThreadFactory-8368-thread-2-processing-n:127.0.0.1:33533_solr) [n:127.0.0.1:33533_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 1619350 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, tag=null [junit4] 2> 1619350 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, tag=null [junit4] 2> 1619350 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d3d5a1: rootName = solr_33533, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@62b4b6f [junit4] 2> 1619350 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c39f85: rootName = solr_34891, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_t4 / com.codahale.metrics.MetricRegistry@e7fe3d5 [junit4] 2> 1619358 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@3dfce4de [junit4] 2> 1619358 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n1, tag=3dfce4de [junit4] 2> 1619358 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=3dfce4de [junit4] 2> 1619358 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 1619358 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37670906 commitCommandVersion:0 [junit4] 2> 1619361 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr 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_node6=1, core_node5=1}, version=4} [junit4] 2> 1619362 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 1619362 INFO (qtp421852300-26615) [n:127.0.0.1:33533_solr x:tlog_replica_test_real_time_get_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=tlog_replica_test_real_time_get_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=12 [junit4] 2> 1619365 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n3, tag=null [junit4] 2> 1619365 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ZkController tlog_replica_test_real_time_get_shard1_replica_t4 stopping background replication from leader [junit4] 2> 1619365 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@538eaeff: rootName = solr_34891, domain = solr.core.tlog_replica_test_real_time_get.shard1.replica_n3, service url = null, agent id = null] for registry solr.core.tlog_replica_test_real_time_get.shard1.replica_n3 / com.codahale.metrics.MetricRegistry@3c923c68 [junit4] 2> 1619366 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_t4] CLOSING SolrCore org.apache.solr.core.SolrCore@596c3a99 [junit4] 2> 1619380 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_t4, tag=596c3a99 [junit4] 2> 1619380 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.SolrCore [tlog_replica_test_real_time_get_shard1_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@1208e58e [junit4] 2> 1619380 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=596c3a99 [junit4] 2> 1619380 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_real_time_get.shard1.replica_n3, tag=1208e58e [junit4] 2> 1619380 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.tlog_replica_test_real_time_get.shard1.leader, tag=1208e58e [junit4] 2> 1619380 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 1619380 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 1619380 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@44c1d278 commitCommandVersion:0 [junit4] 2> 1619380 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3fbb6d41 commitCommandVersion:0 [junit4] 2> 1619383 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr 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_node5=1}, version=5} [junit4] 2> 1619383 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 1619383 INFO (qtp1002186030-26612) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_t4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=tlog_replica_test_real_time_get_shard1_replica_t4&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=33 [junit4] 2> 1619384 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_real_time_get/terms/shard1 to Terms{values={}, version=6} [junit4] 2> 1619384 WARN (zkCallback-9515-thread-1) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 1619384 WARN (zkCallback-9517-thread-2) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 1619385 INFO (qtp1002186030-26614) [n:127.0.0.1:34891_solr x:tlog_replica_test_real_time_get_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=tlog_replica_test_real_time_get_shard1_replica_n3&qt=/admin/cores&deleteDa [...truncated too long message...] -> (1) [junit4] 2> 194662 INFO (zkCallback-254-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 194662 INFO (jetty-closer-134-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:33127_solr [junit4] 2> 194662 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 194662 INFO (zkCallback-235-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 194662 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 194663 INFO (zkCallback-242-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 194663 INFO (zkCallback-235-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 194663 INFO (zkCallback-254-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 194663 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 194663 INFO (jetty-closer-134-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72062358606839833-127.0.0.1:46051_solr-n_0000000005) closing [junit4] 2> 194663 INFO (OverseerStateUpdate-72062358606839833-127.0.0.1:46051_solr-n_0000000005) [n:127.0.0.1:46051_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46051_solr [junit4] 2> 194663 WARN (OverseerAutoScalingTriggerThread-72062358606839833-127.0.0.1:46051_solr-n_0000000005) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 194665 INFO (zkCallback-254-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33127_solr [junit4] 2> 194667 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@574c4520{/solr,null,UNAVAILABLE} [junit4] 2> 194667 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 198833 ERROR (recoveryExecutor-251-thread-1-processing-n:127.0.0.1:33127_solr x:tlog_replica_test_recovery_shard1_replica_t1 c:tlog_replica_test_recovery s:shard1 r:core_node3) [n:127.0.0.1:33127_solr c:tlog_replica_test_recovery s:shard1 r:core_node3 x:tlog_replica_test_recovery_shard1_replica_t1] o.a.s.c.RecoveryStrategy SolrCore not found - cannot recover:tlog_replica_test_recovery_shard1_replica_t1 [junit4] 2> 198836 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2a71c32d{/solr,null,UNAVAILABLE} [junit4] 2> 198837 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 198837 ERROR (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-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> 198837 INFO (SUITE-TestTlogReplica-seed#[45C46B08F8C0E291]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40065 40065 [junit4] 2> 198950 INFO (Thread-99) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40065 40065 [junit4] 2> 198950 WARN (Thread-99) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 32 /solr/collections/tlog_replica_test_create_delete/terms/shard1 [junit4] 2> 31 /solr/configs/conf [junit4] 2> 31 /solr/collections/tlog_replica_test_create_delete/terms/shard2 [junit4] 2> 19 /solr/collections/tlog_replica_test_recovery/terms/shard1 [junit4] 2> 19 /solr/collections/tlog_replica_test_remove_leader/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_kill_tlog_replica/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_basic_leader_election/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_kill_leader/terms/shard1 [junit4] 2> 15 /solr/aliases.json [junit4] 2> 14 /solr/collections/tlog_replica_test_real_time_get/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_delete_by_id/terms/shard1 [junit4] 2> 10 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/terms/shard1 [junit4] 2> 10 /solr/collections/tlog_replica_test_only_leader_indexes/terms/shard1 [junit4] 2> 7 /solr/security.json [junit4] 2> 6 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard2 [junit4] 2> 5 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard1 [junit4] 2> 4 /solr/collections/tlog_replica_test_add_docs/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 22 /solr/collections/tlog_replica_test_create_delete/state.json [junit4] 2> 16 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 15 /solr/clusterprops.json [junit4] 2> 15 /solr/clusterstate.json [junit4] 2> 13 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 9 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 6 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 5 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72062358606839812-core_node13-n_0000000000 [junit4] 2> 4 /solr/overseer_elect/election/72062358606839812-127.0.0.1:46051_solr-n_0000000000 [junit4] 2> 3 /solr/autoscaling.json [junit4] 2> 3 /solr/collections/tlog_replica_test_add_remove_tlog_replica/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_real_time_get/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_only_leader_indexes/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_delete_by_id/state.json [junit4] 2> 2 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 2 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/72062358606839833-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_kill_tlog_replica/leader_elect/shard1/election/72062358606839812-core_node4-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/queue-work [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_45C46B08F8C0E291-002 [junit4] 2> Nov 21, 2018 1:53:51 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=Lucene80, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4bcc1aca), locale=lrc, timezone=Asia/Chita [junit4] 2> NOTE: Linux 4.15.0-39-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=195227872,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplica, TestTlogReplica] [junit4] Completed [5/5 (5!)] on J1 in 93.58s, 13 tests, 1 failure <<< FAILURES! [...truncated 15 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1567: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 65 tests, 5 failures [seed: 45C46B08F8C0E291] Total time: 3 minutes 21 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=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/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=08dd681f0febcf73af94b47ea742294bf4dd8701, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux [WARNINGS] Computing warning deltas based on reference build #23243 Recording test results Setting ANT_1_8_2_HOME=/var/lib/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=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
