Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2948/ Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseParallelGC
4 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:33309/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:33309/solr at __randomizedtesting.SeedInfo.seed([11BE23D0EDE4CD7D:D04E5A7CC0B407DA]: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:36327/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:36327/solr at __randomizedtesting.SeedInfo.seed([11BE23D0EDE4CD7D:D04E5A7CC0B407DA]: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:41651/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:41651/solr at __randomizedtesting.SeedInfo.seed([11BE23D0EDE4CD7D:D04E5A7CC0B407DA]: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:44365/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:44365/solr at __randomizedtesting.SeedInfo.seed([11BE23D0EDE4CD7D:D04E5A7CC0B407DA]: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 12569 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/init-core-data-001 [junit4] 2> 117364 WARN (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=201 numCloses=201 [junit4] 2> 117364 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 117365 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 117367 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 117372 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001 [junit4] 2> 117372 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 117381 INFO (Thread-340) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 117381 INFO (Thread-340) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 117434 ERROR (Thread-340) [ ] 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> 117481 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:40023 [junit4] 2> 117520 INFO (zkConnectionManagerCallback-211-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117538 INFO (jetty-launcher-208-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> 117546 INFO (jetty-launcher-208-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> 117611 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 117611 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 117611 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 117621 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@65c7c601{/solr,null,AVAILABLE} [junit4] 2> 117622 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 117622 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 117622 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 117622 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@134994ae{/solr,null,AVAILABLE} [junit4] 2> 117625 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a3b586c{SSL,[ssl, http/1.1]}{127.0.0.1:44365} [junit4] 2> 117625 INFO (jetty-launcher-208-thread-2) [ ] o.e.j.s.Server Started @117664ms [junit4] 2> 117625 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2663b677{SSL,[ssl, http/1.1]}{127.0.0.1:46003} [junit4] 2> 117625 INFO (jetty-launcher-208-thread-1) [ ] o.e.j.s.Server Started @117664ms [junit4] 2> 117625 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44365} [junit4] 2> 117625 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46003} [junit4] 2> 117626 ERROR (jetty-launcher-208-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 117626 ERROR (jetty-launcher-208-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 117626 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 117626 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 117626 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.6.0 [junit4] 2> 117626 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.6.0 [junit4] 2> 117626 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 117626 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 117626 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 117626 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 117626 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-20T17:42:41.050250Z [junit4] 2> 117626 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-20T17:42:41.050239Z [junit4] 2> 117657 INFO (zkConnectionManagerCallback-213-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117657 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 117663 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10041bd32140001, likely client has closed socket [junit4] 2> 117682 INFO (zkConnectionManagerCallback-215-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117683 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 117873 INFO (jetty-launcher-208-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40023/solr [junit4] 2> 117884 INFO (zkConnectionManagerCallback-219-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117889 INFO (jetty-launcher-208-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40023/solr [junit4] 2> 117892 INFO (zkConnectionManagerCallback-223-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117904 INFO (zkConnectionManagerCallback-225-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117920 INFO (zkConnectionManagerCallback-229-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117939 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44365_solr [junit4] 2> 117940 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.c.Overseer Overseer (id=72129874882723846-127.0.0.1:44365_solr-n_0000000000) starting [junit4] 2> 117957 INFO (zkConnectionManagerCallback-236-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 117959 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40023/solr ready [junit4] 2> 117959 INFO (OverseerStateUpdate-72129874882723846-127.0.0.1:44365_solr-n_0000000000) [n:127.0.0.1:44365_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44365_solr [junit4] 2> 117970 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44365_solr [junit4] 2> 117981 INFO (zkCallback-228-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 117989 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 117997 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118007 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 118008 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46003_solr [junit4] 2> 118008 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44365.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118015 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44365.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118015 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44365.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118017 INFO (jetty-launcher-208-thread-2) [n:127.0.0.1:44365_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node2/. [junit4] 2> 118021 INFO (zkCallback-235-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118021 INFO (zkCallback-235-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 118033 INFO (zkCallback-228-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 118041 INFO (zkCallback-222-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 118048 INFO (zkConnectionManagerCallback-242-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118049 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 118050 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40023/solr ready [junit4] 2> 118050 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 118066 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46003.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118093 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46003.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118093 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46003.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 118093 INFO (jetty-launcher-208-thread-1) [n:127.0.0.1:46003_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node1/. [junit4] 2> 118109 INFO (zkConnectionManagerCallback-245-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118112 INFO (zkConnectionManagerCallback-250-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118112 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 118113 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40023/solr ready [junit4] 2> 118114 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 118130 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_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> 118131 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_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=0 [junit4] 2> 118145 INFO (TEST-TestTlogReplica.testAddDocs-seed#[11BE23D0EDE4CD7D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddDocs [junit4] 2> 118147 INFO (qtp1758396656-1222) [n:127.0.0.1:46003_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_add_docs&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=3&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 118149 INFO (OverseerThreadFactory-471-thread-1-processing-n:127.0.0.1:44365_solr) [n:127.0.0.1:44365_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_add_docs [junit4] 2> 118251 WARN (OverseerThreadFactory-471-thread-1-processing-n:127.0.0.1:44365_solr) [n:127.0.0.1:44365_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection tlog_replica_test_add_docs is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance. [junit4] 2> 118265 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_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> 118291 INFO (qtp700402692-1232) [n:127.0.0.1:44365_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> 118297 INFO (OverseerStateUpdate-72129874882723846-127.0.0.1:44365_solr-n_0000000000) [n:127.0.0.1:44365_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:44365/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 118299 INFO (OverseerStateUpdate-72129874882723846-127.0.0.1:44365_solr-n_0000000000) [n:127.0.0.1:44365_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:46003/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 118302 INFO (OverseerStateUpdate-72129874882723846-127.0.0.1:44365_solr-n_0000000000) [n:127.0.0.1:44365_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t5", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:46003/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 118515 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t3&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 118518 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 118518 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 118518 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t5&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 119534 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 119537 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 119538 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t3] Schema name=minimal [junit4] 2> 119539 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.6.0 [junit4] 2> 119541 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t5] Schema name=minimal [junit4] 2> 119541 WARN (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly. [junit4] 2> 119543 WARN (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly. [junit4] 2> 119543 WARN (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly. [junit4] 2> 119543 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 119543 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t5' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 119543 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 119543 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t3' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 119543 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46003.solr.core.tlog_replica_test_add_docs.shard1.replica_t5' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 119544 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46003.solr.core.tlog_replica_test_add_docs.shard1.replica_t3' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 119544 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node1/tlog_replica_test_add_docs_shard1_replica_t5], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node1/./tlog_replica_test_add_docs_shard1_replica_t5/data/] [junit4] 2> 119544 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node1/tlog_replica_test_add_docs_shard1_replica_t3], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node1/./tlog_replica_test_add_docs_shard1_replica_t3/data/] [junit4] 2> 119544 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t1] Schema name=minimal [junit4] 2> 119546 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 119546 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t1' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 119547 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44365.solr.core.tlog_replica_test_add_docs.shard1.replica_t1' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@184376fa [junit4] 2> 119547 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node2/tlog_replica_test_add_docs_shard1_replica_t1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-001/tempDir-001/node2/./tlog_replica_test_add_docs_shard1_replica_t1/data/] [junit4] 2> 119603 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 119603 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 119604 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 119604 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 119604 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 119604 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 119605 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 119605 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 119605 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.s.SolrIndexSearcher Opening [Searcher@7cc0c93e[tlog_replica_test_add_docs_shard1_replica_t3] main] [junit4] 2> 119606 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.s.SolrIndexSearcher Opening [Searcher@40eb8a71[tlog_replica_test_add_docs_shard1_replica_t5] main] [junit4] 2> 119609 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 119609 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 119609 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 119609 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 119610 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 119610 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 119610 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614867189500739584 [junit4] 2> 119610 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614867189500739584 [junit4] 2> 119612 INFO (searcherExecutor-480-thread-1-processing-n:127.0.0.1:46003_solr x:tlog_replica_test_add_docs_shard1_replica_t5 c:tlog_replica_test_add_docs s:shard1 r:core_node6) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t5] Registered new searcher Searcher@40eb8a71[tlog_replica_test_add_docs_shard1_replica_t5] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 119613 INFO (searcherExecutor-481-thread-1-processing-n:127.0.0.1:46003_solr x:tlog_replica_test_add_docs_shard1_replica_t3 c:tlog_replica_test_add_docs s:shard1 r:core_node4) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t3] Registered new searcher Searcher@7cc0c93e[tlog_replica_test_add_docs_shard1_replica_t3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 119616 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0}, version=0} [junit4] 2> 119616 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 119616 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node4=0}, version=1} [junit4] 2> 119617 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 119617 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 119618 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 119618 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 119619 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=2 timeoutin=9999ms [junit4] 2> 119620 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4af42dcb[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 119620 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 119621 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 119621 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 119622 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614867189513322496 [junit4] 2> 119622 INFO (searcherExecutor-482-thread-1-processing-n:127.0.0.1:44365_solr x:tlog_replica_test_add_docs_shard1_replica_t1 c:tlog_replica_test_add_docs s:shard1 r:core_node2) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@4af42dcb[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 119625 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node2=0, core_node4=0}, version=2} [junit4] 2> 120120 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 120120 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 120120 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/ [junit4] 2> 120120 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t5 url=https://127.0.0.1:46003/solr START replicas=[https://127.0.0.1:44365/solr/tlog_replica_test_add_docs_shard1_replica_t1/, https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t3/] nUpdates=100 [junit4] 2> 120121 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t5 url=https://127.0.0.1:46003/solr DONE. We have no versions. sync failed. [junit4] 2> 120128 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 120128 INFO (qtp1758396656-1220) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t3] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 120128 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 120128 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] 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> 120128 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 120128 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t5 stopping background replication from leader [junit4] 2> 120131 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/ shard1 [junit4] 2> 120232 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 120234 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t5&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=1716 [junit4] 2> 120334 INFO (zkCallback-222-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 120619 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t3 starting background replication from leader [junit4] 2> 120619 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 120620 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 120620 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 120622 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t3&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2107 [junit4] 2> 120627 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t1 starting background replication from leader [junit4] 2> 120627 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 120628 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 120628 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 120630 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2112 [junit4] 2> 120633 INFO (qtp1758396656-1222) [n:127.0.0.1:46003_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> 120730 INFO (zkCallback-222-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 120730 INFO (zkCallback-228-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_add_docs/state.json] for collection [tlog_replica_test_add_docs] has occurred - updating... (live nodes size: [2]) [junit4] 2> 121421 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/ [junit4] 2> 121424 INFO (qtp1758396656-1218) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 121424 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 121424 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 121424 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 121424 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 121424 INFO (indexFetcher-498-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 121425 INFO (indexFetcher-498-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45d65846[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 121427 INFO (searcherExecutor-482-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@45d65846[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 121633 INFO (qtp1758396656-1222) [n:127.0.0.1:46003_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_add_docs&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=3&wt=javabin&version=2} status=0 QTime=3485 [junit4] 2> 121643 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=1, core_node2=1, core_node4=1}, version=3} [junit4] 2> 121652 INFO (qtp700402692-1233) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/&wt=javabin&version=2}{add=[1 (1614867191626203136)]} 0 0 [junit4] 2> 121657 INFO (qtp1758396656-1218) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t3] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/&wt=javabin&version=2}{add=[1 (1614867191626203136)]} 0 5 [junit4] 2> 121657 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1614867191626203136)]} 0 20 [junit4] 2> 121666 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 121667 INFO (qtp1758396656-1223) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node4 x:tlog_replica_test_add_docs_shard1_replica_t3] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t3] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 121672 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1614867191662903296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 121672 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@36741ac2 commitCommandVersion:1614867191662903296 [junit4] 2> 121680 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.s.SolrIndexSearcher Opening [Searcher@4dfedb0f[tlog_replica_test_add_docs_shard1_replica_t5] main] [junit4] 2> 121680 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 121681 INFO (searcherExecutor-480-thread-1-processing-n:127.0.0.1:46003_solr x:tlog_replica_test_add_docs_shard1_replica_t5 c:tlog_replica_test_add_docs s:shard1 r:core_node6) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t5] Registered new searcher Searcher@4dfedb0f[tlog_replica_test_add_docs_shard1_replica_t5] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.6.0):C1)))} [junit4] 2> 121682 INFO (qtp1758396656-1219) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 10 [junit4] 2> 121682 INFO (qtp1758396656-1222) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_add_docs:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 22 [junit4] 2> 121691 INFO (qtp1758396656-1222) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 121697 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 121799 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 121900 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122002 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122104 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122154 INFO (OverseerCollectionConfigSetProcessor-72129874882723846-127.0.0.1:44365_solr-n_0000000000) [n:127.0.0.1:44365_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> 122206 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122308 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122409 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122511 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122613 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122657 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:46003/solr/tlog_replica_test_add_docs_shard1_replica_t5/ [junit4] 2> 122658 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 122659 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 122659 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1540057365096 [junit4] 2> 122659 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 122659 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 122659 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 122660 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 122661 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 18 [junit4] 2> 122663 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@2a405a28 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c86b063) [junit4] 2> 122668 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=2 [junit4] 2> 122672 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.pos&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122674 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Direct_0.dvdd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122675 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122677 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.tib&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122678 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122680 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_FST50_0.tfp&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122682 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122684 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122686 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_FST50_0.pos&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122687 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122689 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122691 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_FST50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122693 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122695 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Direct_0.dvdm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122696 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122697 INFO (qtp1758396656-1224) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122699 INFO (qtp1758396656-1295) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 122700 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 2969, Bytes skipped downloading: 0 [junit4] 2> 122700 INFO (indexFetcher-496-thread-1) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2969) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@2a405a28 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3c86b063) [junit4] 2> 122702 INFO (indexFetcher-496-thread-1) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 122703 INFO (indexFetcher-496-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a13b198[tlog_replica_test_add_docs_shard1_replica_t3] main] [junit4] 2> 122704 INFO (searcherExecutor-481-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t3] Registered new searcher Searcher@3a13b198[tlog_replica_test_add_docs_shard1_replica_t3] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.6.0):C1)))} [junit4] 2> 122714 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122816 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 122917 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123019 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123120 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123222 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123323 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123425 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123526 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123628 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123729 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123830 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 123932 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 124037 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 124139 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 124240 INFO (qtp700402692-1231) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 124342 INFO (qtp700402692-1227) [n:127.0.0.1:44365_solr c:tlog_replica_test_add_docs s:shard1 r:core_node2 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 124421 INFO (qtp1758396656-1218) [n:127.0.0.1:46003_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t5] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t5] webapp [...truncated too long message...] teReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 213326 INFO (zkCallback-237-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 213326 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 213328 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@53b5059e{/solr,null,UNAVAILABLE} [junit4] 2> 213328 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 213329 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 213329 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@761ff2e2: rootName = solr_41651, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@69ba514f [junit4] 2> 213329 INFO (jetty-closer-134-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41651_solr [junit4] 2> 213330 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 213330 INFO (zkCallback-244-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 213330 INFO (zkCallback-237-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 213333 INFO (jetty-closer-134-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72130080313376793-127.0.0.1:41651_solr-n_0000000005) closing [junit4] 2> 213333 INFO (OverseerStateUpdate-72130080313376793-127.0.0.1:41651_solr-n_0000000005) [n:127.0.0.1:41651_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41651_solr [junit4] 2> 213334 WARN (OverseerAutoScalingTriggerThread-72130080313376793-127.0.0.1:41651_solr-n_0000000005) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 213335 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4db0a4e9{/solr,null,UNAVAILABLE} [junit4] 2> 213335 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 213338 ERROR (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-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> 213339 INFO (SUITE-TestTlogReplica-seed#[11BE23D0EDE4CD7D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40705 40705 [junit4] 2> 213445 INFO (Thread-98) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40705 40705 [junit4] 2> 213446 WARN (Thread-98) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 32 /solr/configs/conf [junit4] 2> 32 /solr/collections/tlog_replica_test_create_delete/terms/shard1 [junit4] 2> 30 /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_leader/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_basic_leader_election/terms/shard1 [junit4] 2> 16 /solr/collections/tlog_replica_test_kill_tlog_replica/terms/shard1 [junit4] 2> 15 /solr/aliases.json [junit4] 2> 14 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/terms/shard1 [junit4] 2> 14 /solr/collections/tlog_replica_test_add_docs/terms/shard1 [junit4] 2> 10 /solr/collections/tlog_replica_test_delete_by_id/terms/shard1 [junit4] 2> 10 /solr/collections/tlog_replica_test_real_time_get/terms/shard1 [junit4] 2> 10 /solr/collections/tlog_replica_test_only_leader_indexes/terms/shard1 [junit4] 2> 8 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard2 [junit4] 2> 7 /solr/security.json [junit4] 2> 6 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 25 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 24 /solr/collections/tlog_replica_test_create_delete/state.json [junit4] 2> 15 /solr/clusterprops.json [junit4] 2> 15 /solr/clusterstate.json [junit4] 2> 13 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 9 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 8 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 4 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 4 /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/overseer_elect/election/72130080313376772-127.0.0.1:40915_solr-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/72130080313376772-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72130080313376772-core_node13-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/72130080313376781-127.0.0.1:41651_solr-n_0000000002 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72130080313376775-core_node9-n_0000000001 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard1/election/72130080313376772-core_node7-n_0000000002 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72130080313376775-core_node16-n_0000000001 [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> 4 /solr/overseer/queue [junit4] 2> 4 /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 4 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 4 /solr/overseer/queue-work [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_11BE23D0EDE4CD7D-002 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {foo=FST50, title_s=FST50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_s=FST50}, docValues:{_version_=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Direct), inplace_updatable_int=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1634, maxMBSortInHeap=5.764438924398535, sim=RandomSimilarity(queryNorm=true): {}, locale=nl-SX, timezone=America/Glace_Bay [junit4] 2> NOTE: Linux 4.15.0-36-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=76036840,total=475529216 [junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplica, TestTlogReplica] [junit4] Completed [5/5 (5!)] on J1 in 99.34s, 13 tests, 1 failure <<< FAILURES! [...truncated 15 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1567: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 65 tests, 5 failures [seed: 11BE23D0EDE4CD7D] Total time: 3 minutes 35 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=36ce83bc9add02a900e38b396b42c3c729846598, workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux [WARNINGS] Computing warning deltas based on reference build #2947 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
