Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/3118/ Java: 64bit/jdk-10.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
3 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testRecovery Error Message: Can not find doc 7 in https://127.0.0.1:36483/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:36483/solr at __randomizedtesting.SeedInfo.seed([D3B120673FBD433A:124159CB12ED899D]: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:36635/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:36635/solr at __randomizedtesting.SeedInfo.seed([D3B120673FBD433A:124159CB12ED899D]: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:45149/solr Stack Trace: java.lang.AssertionError: Can not find doc 7 in https://127.0.0.1:45149/solr at __randomizedtesting.SeedInfo.seed([D3B120673FBD433A:124159CB12ED899D]: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 13537 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> 570051 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/init-core-data-001 [junit4] 2> 570052 WARN (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=20 numCloses=20 [junit4] 2> 570052 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 570052 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 570053 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-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/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001 [junit4] 2> 570053 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 570053 INFO (Thread-1486) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 570053 INFO (Thread-1486) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 570054 ERROR (Thread-1486) [ ] 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> 570153 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44809 [junit4] 2> 570154 INFO (zkConnectionManagerCallback-1952-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570157 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10 [junit4] 2> 570157 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10 [junit4] 2> 570158 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 570158 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 570158 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 570158 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c12b790{/solr,null,AVAILABLE} [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4006b06d{/solr,null,AVAILABLE} [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1dbb1ef2{SSL,[ssl, http/1.1]}{127.0.0.1:39081} [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52fdc28c{SSL,[ssl, http/1.1]}{127.0.0.1:45149} [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.e.j.s.Server Started @570183ms [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.e.j.s.Server Started @570183ms [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39081} [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45149} [junit4] 2> 570159 ERROR (jetty-launcher-1949-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 570159 ERROR (jetty-launcher-1949-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.7.0 [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.7.0 [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-19T07:11:17.110901Z [junit4] 2> 570159 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-19T07:11:17.110908Z [junit4] 2> 570160 INFO (zkConnectionManagerCallback-1955-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570160 INFO (zkConnectionManagerCallback-1956-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570161 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 570161 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 570164 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 0x100765bd3480002, likely client has closed socket [junit4] 2> 570291 INFO (jetty-launcher-1949-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44809/solr [junit4] 2> 570292 INFO (zkConnectionManagerCallback-1960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570294 INFO (zkConnectionManagerCallback-1962-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570300 INFO (jetty-launcher-1949-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44809/solr [junit4] 2> 570300 INFO (zkConnectionManagerCallback-1968-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570301 INFO (zkConnectionManagerCallback-1970-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570305 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45149_solr [junit4] 2> 570306 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.c.Overseer Overseer (id=72187730796740614-127.0.0.1:45149_solr-n_0000000000) starting [junit4] 2> 570308 INFO (zkConnectionManagerCallback-1977-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570309 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44809/solr ready [junit4] 2> 570310 INFO (OverseerStateUpdate-72187730796740614-127.0.0.1:45149_solr-n_0000000000) [n:127.0.0.1:45149_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45149_solr [junit4] 2> 570310 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45149_solr [junit4] 2> 570311 INFO (zkCallback-1976-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 570311 INFO (zkCallback-1969-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 570321 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 570330 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 570331 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45149.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570331 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 570331 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39081_solr [junit4] 2> 570332 INFO (zkCallback-1969-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 570332 INFO (zkCallback-1976-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 570332 INFO (zkCallback-1961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 570336 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45149.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570336 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45149.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570337 INFO (jetty-launcher-1949-thread-1) [n:127.0.0.1:45149_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node1/. [junit4] 2> 570340 INFO (zkConnectionManagerCallback-1983-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570341 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 570341 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44809/solr ready [junit4] 2> 570345 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 570353 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39081.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570358 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39081.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570358 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39081.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 570359 INFO (jetty-launcher-1949-thread-2) [n:127.0.0.1:39081_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node2/. [junit4] 2> 570375 INFO (zkConnectionManagerCallback-1986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570378 INFO (zkConnectionManagerCallback-1991-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 570378 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 570379 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44809/solr ready [junit4] 2> 570380 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 570389 INFO (qtp1179154266-7474) [n:127.0.0.1:39081_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> 570391 INFO (qtp1179154266-7474) [n:127.0.0.1:39081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 570398 INFO (TEST-TestTlogReplica.testAddDocs-seed#[D3B120673FBD433A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddDocs [junit4] 2> 570399 INFO (qtp1179154266-7473) [n:127.0.0.1:39081_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> 570400 INFO (OverseerThreadFactory-3382-thread-1-processing-n:127.0.0.1:45149_solr) [n:127.0.0.1:45149_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_add_docs [junit4] 2> 570507 WARN (OverseerThreadFactory-3382-thread-1-processing-n:127.0.0.1:45149_solr) [n:127.0.0.1:45149_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> 570511 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_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> 570519 INFO (qtp908892177-7479) [n:127.0.0.1:45149_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> 570523 INFO (OverseerStateUpdate-72187730796740614-127.0.0.1:45149_solr-n_0000000000) [n:127.0.0.1:45149_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:45149/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 570524 INFO (OverseerStateUpdate-72187730796740614-127.0.0.1:45149_solr-n_0000000000) [n:127.0.0.1:45149_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:39081/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 570525 INFO (OverseerStateUpdate-72187730796740614-127.0.0.1:45149_solr-n_0000000000) [n:127.0.0.1:45149_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_add_docs", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_add_docs_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:39081/solr";, [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 570729 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 570729 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 570729 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 570730 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 571745 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 571745 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 571746 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 571750 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t4] Schema name=minimal [junit4] 2> 571750 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t2] Schema name=minimal [junit4] 2> 571750 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema [tlog_replica_test_add_docs_shard1_replica_t1] Schema name=minimal [junit4] 2> 571751 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 571751 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 571751 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 571751 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t1' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 571751 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t2' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 571751 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_add_docs_shard1_replica_t4' using configuration from collection tlog_replica_test_add_docs, trusted=true [junit4] 2> 571751 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39081.solr.core.tlog_replica_test_add_docs.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 571751 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45149.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@2282074f [junit4] 2> 571751 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39081.solr.core.tlog_replica_test_add_docs.shard1.replica_t2' (registry 'solr.core.tlog_replica_test_add_docs.shard1.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2282074f [junit4] 2> 571751 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node1/tlog_replica_test_add_docs_shard1_replica_t1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node1/./tlog_replica_test_add_docs_shard1_replica_t1/data/] [junit4] 2> 571751 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node2/tlog_replica_test_add_docs_shard1_replica_t4], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node2/./tlog_replica_test_add_docs_shard1_replica_t4/data/] [junit4] 2> 571751 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrCore [[tlog_replica_test_add_docs_shard1_replica_t2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node2/tlog_replica_test_add_docs_shard1_replica_t2], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-001/tempDir-001/node2/./tlog_replica_test_add_docs_shard1_replica_t2/data/] [junit4] 2> 571789 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 571789 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 571790 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 571790 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 571791 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.SolrIndexSearcher Opening [Searcher@67824cbc[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 571792 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 571792 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 571793 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 571793 INFO (searcherExecutor-3391-thread-1-processing-n:127.0.0.1:45149_solr x:tlog_replica_test_add_docs_shard1_replica_t1 c:tlog_replica_test_add_docs s:shard1 r:core_node3) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@67824cbc[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 571793 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617545373935468544 [junit4] 2> 571795 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 571795 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 571796 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 571796 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 571797 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 571797 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@5868209d[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 571798 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 571798 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 571798 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 571799 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617545373941760000 [junit4] 2> 571799 INFO (searcherExecutor-3393-thread-1-processing-n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t4 c:tlog_replica_test_add_docs s:shard1 r:core_node6) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@5868209d[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 571800 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoutin=9999ms [junit4] 2> 571801 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node3=0}, version=1} [junit4] 2> 571804 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 571804 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 571805 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 571805 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 571807 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.SolrIndexSearcher Opening [Searcher@48e67752[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 571807 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 571808 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 571808 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 571808 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617545373951197184 [junit4] 2> 571809 INFO (searcherExecutor-3392-thread-1-processing-n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t2 c:tlog_replica_test_add_docs s:shard1 r:core_node5) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@48e67752[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 571811 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2} [junit4] 2> 572301 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 572301 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 572301 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/ [junit4] 2> 572301 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t1 url=https://127.0.0.1:45149/solr START replicas=[https://127.0.0.1:39081/solr/tlog_replica_test_add_docs_shard1_replica_t2/, https://127.0.0.1:39081/solr/tlog_replica_test_add_docs_shard1_replica_t4/] nUpdates=100 [junit4] 2> 572302 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_add_docs_shard1_replica_t1 url=https://127.0.0.1:45149/solr DONE. We have no versions. sync failed. [junit4] 2> 572309 INFO (qtp1179154266-7542) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=6 [junit4] 2> 572312 INFO (qtp1179154266-7474) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 572313 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 572313 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.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> 572313 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 572313 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t1 stopping background replication from leader [junit4] 2> 572316 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/ shard1 [junit4] 2> 572468 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 572471 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=1742 [junit4] 2> 572570 INFO (zkCallback-1969-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> 572829 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t4 starting background replication from leader [junit4] 2> 572829 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01 [junit4] 2> 572837 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ZkController tlog_replica_test_add_docs_shard1_replica_t2 starting background replication from leader [junit4] 2> 572837 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:01 [junit4] 2> 572845 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 572845 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 572849 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/ [junit4] 2> 572852 INFO (qtp908892177-7475) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 572852 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 572852 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 572852 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 572852 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 572852 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 572863 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 572863 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 572863 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a1f495a[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 572867 INFO (searcherExecutor-3393-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@5a1f495a[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 572953 INFO (qtp1179154266-7549) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2223 [junit4] 2> 572953 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_add_docs_shard1_replica_t4&action=CREATE&numShards=1&collection=tlog_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=TLOG} status=0 QTime=2224 [junit4] 2> 572960 INFO (qtp1179154266-7473) [n:127.0.0.1:39081_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> 572971 INFO (zkCallback-1969-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> 572971 INFO (zkCallback-1961-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> 573738 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Updated masterUrl to https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/ [junit4] 2> 573739 INFO (qtp908892177-7475) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 573739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 573739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 573739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 573739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 573739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 573745 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c6ab7c0[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 573748 INFO (searcherExecutor-3392-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@6c6ab7c0[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 573831 INFO (qtp908892177-7475) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 573831 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 573831 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 573831 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 573831 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 573831 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 573832 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ec821b6[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 573833 INFO (searcherExecutor-3393-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@2ec821b6[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 573960 INFO (qtp1179154266-7473) [n:127.0.0.1:39081_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=3560 [junit4] 2> 573989 INFO (qtp908892177-7465) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_add_docs/terms/shard1 to Terms{values={core_node6=1, core_node3=1, core_node5=1}, version=3} [junit4] 2> 573990 INFO (qtp1179154266-7473) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/&wt=javabin&version=2}{add=[1 (1617545376228704256)]} 0 5 [junit4] 2> 573990 INFO (qtp1179154266-7548) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/&wt=javabin&version=2}{add=[1 (1617545376228704256)]} 0 3 [junit4] 2> 573990 INFO (qtp908892177-7465) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1617545376228704256)]} 0 10 [junit4] 2> 573993 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617545376242335744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 573993 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7271217d commitCommandVersion:1617545376242335744 [junit4] 2> 573996 INFO (qtp1179154266-7467) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 573997 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.s.SolrIndexSearcher Opening [Searcher@296a6c91[tlog_replica_test_add_docs_shard1_replica_t1] main] [junit4] 2> 573997 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0 [junit4] 2> 573997 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 573998 INFO (searcherExecutor-3391-thread-1-processing-n:127.0.0.1:45149_solr x:tlog_replica_test_add_docs_shard1_replica_t1 c:tlog_replica_test_add_docs s:shard1 r:core_node3) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t1] Registered new searcher Searcher@296a6c91[tlog_replica_test_add_docs_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C1)))} [junit4] 2> 573998 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45149/solr/tlog_replica_test_add_docs_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 5 [junit4] 2> 573999 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.u.p.LogUpdateProcessorFactory [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/update params={_stateVer_=tlog_replica_test_add_docs:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7 [junit4] 2> 574063 INFO (qtp908892177-7479) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 574069 INFO (qtp908892177-7475) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 574084 INFO (qtp908892177-7475) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/admin/plugins params={qt=/admin/plugins&stats=true&wt=javabin&version=2} status=0 QTime=14 [junit4] 2> 574102 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574203 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574305 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574402 INFO (OverseerCollectionConfigSetProcessor-72187730796740614-127.0.0.1:45149_solr-n_0000000000) [n:127.0.0.1:45149_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> 574406 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574507 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574608 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574709 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574739 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 574739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 574739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1542611480944 [junit4] 2> 574739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 574739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 574739 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 574740 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 574740 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 13 [junit4] 2> 574742 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@1f798f9a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46d4fcf1) [junit4] 2> 574743 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574744 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574745 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574746 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574747 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574747 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.pos&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574748 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574749 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574749 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574750 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574751 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574752 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574753 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574753 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 2395, Bytes skipped downloading: 0 [junit4] 2> 574753 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2395) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@1f798f9a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46d4fcf1) [junit4] 2> 574754 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 574755 INFO (indexFetcher-3409-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dee33f8[tlog_replica_test_add_docs_shard1_replica_t2] main] [junit4] 2> 574756 INFO (searcherExecutor-3392-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t2] Registered new searcher Searcher@1dee33f8[tlog_replica_test_add_docs_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C1)))} [junit4] 2> 574810 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 574815 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node5 x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t2] webapp=/solr path=/admin/plugins params={qt=/admin/plugins&stats=true&wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 574828 INFO (qtp1179154266-7542) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 574832 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 574832 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 574832 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Master's version: 1542611480944 [junit4] 2> 574832 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 574833 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 574833 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 574833 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 574834 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 13 [junit4] 2> 574834 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@46d1c66b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ea42974) [junit4] 2> 574835 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574846 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574849 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574850 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574852 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574853 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.pos&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574854 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574856 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574857 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574858 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Asserting_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574859 INFO (qtp908892177-7478) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574861 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574863 INFO (qtp908892177-7481) [n:127.0.0.1:45149_solr c:tlog_replica_test_add_docs s:shard1 r:core_node3 x:tlog_replica_test_add_docs_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 574864 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Bytes downloaded: 2395, Bytes skipped downloading: 0 [junit4] 2> 574864 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2395) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@46d1c66b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ea42974) [junit4] 2> 574865 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 574866 INFO (indexFetcher-3407-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e436bfa[tlog_replica_test_add_docs_shard1_replica_t4] main] [junit4] 2> 574867 INFO (searcherExecutor-3393-thread-1) [ ] o.a.s.c.SolrCore [tlog_replica_test_add_docs_shard1_replica_t4] Registered new searcher Searcher@5e436bfa[tlog_replica_test_add_docs_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):C1)))} [junit4] 2> 574934 INFO (qtp1179154266-7542) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 574940 INFO (qtp1179154266-7542) [n:127.0.0.1:39081_solr c:tlog_replica_test_add_docs s:shard1 r:core_node6 x:tlog_replica_test_add_docs_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_add_docs_shard1_replica_t4] webapp=/solr path=/admin/plugins params={qt=/admin/plugins&stats=true&wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 574943 INFO (TEST-TestTlogReplica.testAddDocs-seed#[D3B120673FBD433A]) [ ] o.a.s.c.TestTlogReplica tearDown deleting collection [junit4] 2> 574943 INFO (qtp1179154266-7542) [n:127.0.0.1:39081_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=tlog_replica_test_add_docs&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 574950 INFO (OverseerThreadFactory-3382-thread-2-processing-n:127.0.0.1:45149_solr) [n:127.0.0.1:45149_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 574951 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.tlog_replica_test_add_docs.shard1.replica_t2, tag=null [junit4] 2> 574951 INFO (qtp1179154266-7476) [n:127.0.0.1:39081_solr x:tlog_replica_test_add_docs_shard1_replica_t2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2031d228: rootName = solr_39081, domain = solr.core.tlog_replica_test_add_docs.shard1.replica_t2, service url = null, agent id = null] for registry solr.core.tlog_replica_test_add_docs.shard1.replica_t2 / com.codahale.metrics.MetricRegistry@1b6675b3 [juni [...truncated too long message...] it4] 2> 191448 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 191449 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b8d3b09: rootName = solr_41487, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@58e84674 [junit4] 2> 191449 INFO (zkCallback-242-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 191453 INFO (zkCallback-259-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 191453 INFO (jetty-closer-134-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72187927430692889-127.0.0.1:36635_solr-n_0000000005) closing [junit4] 2> 191453 INFO (jetty-closer-134-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41487_solr [junit4] 2> 191453 INFO (OverseerStateUpdate-72187927430692889-127.0.0.1:36635_solr-n_0000000005) [n:127.0.0.1:36635_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36635_solr [junit4] 2> 191454 INFO (jetty-closer-134-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 191454 INFO (zkCallback-259-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 191456 INFO (zkCallback-252-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 191456 INFO (zkCallback-235-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 191457 WARN (OverseerAutoScalingTriggerThread-72187927430692889-127.0.0.1:36635_solr-n_0000000005) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 191457 INFO (zkCallback-242-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 191457 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@313e4544{/solr,null,UNAVAILABLE} [junit4] 2> 191457 INFO (jetty-closer-134-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 191457 INFO (zkCallback-235-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 191461 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5cafba77{/solr,null,UNAVAILABLE} [junit4] 2> 191461 INFO (jetty-closer-134-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 191462 ERROR (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-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> 191462 INFO (SUITE-TestTlogReplica-seed#[D3B120673FBD433A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43673 43673 [junit4] 2> 191514 INFO (Thread-103) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43673 43673 [junit4] 2> 191515 WARN (Thread-103) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 33 /solr/collections/tlog_replica_test_create_delete/terms/shard1 [junit4] 2> 31 /solr/configs/conf [junit4] 2> 31 /solr/collections/tlog_replica_test_create_delete/terms/shard2 [junit4] 2> 20 /solr/collections/tlog_replica_test_remove_leader/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_basic_leader_election/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_kill_leader/terms/shard1 [junit4] 2> 17 /solr/collections/tlog_replica_test_recovery/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_add_docs/terms/shard1 [junit4] 2> 13 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/terms/shard1 [junit4] 2> 11 /solr/collections/tlog_replica_test_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> 7 /solr/collections/tlog_replica_test_add_remove_tlog_replica/terms/shard1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 25 /solr/collections/tlog_replica_test_out_of_order_db_qwith_in_place_updates/state.json [junit4] 2> 20 /solr/collections/tlog_replica_test_create_delete/state.json [junit4] 2> 15 /solr/clusterprops.json [junit4] 2> 15 /solr/clusterstate.json [junit4] 2> 14 /solr/collections/tlog_replica_test_kill_leader/state.json [junit4] 2> 13 /solr/collections/tlog_replica_test_kill_tlog_replica/state.json [junit4] 2> 12 /solr/collections/tlog_replica_test_recovery/state.json [junit4] 2> 11 /solr/collections/tlog_replica_test_basic_leader_election/state.json [junit4] 2> 9 /solr/collections/tlog_replica_test_remove_leader/state.json [junit4] 2> 5 /solr/overseer_elect/election/72187927430692870-127.0.0.1:41487_solr-n_0000000000 [junit4] 2> 4 /solr/collections/tlog_replica_test_add_docs/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72187927430692870-core_node15-n_0000000001 [junit4] 2> 3 /solr/collections/tlog_replica_test_add_remove_tlog_replica/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_real_time_get/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_only_leader_indexes/state.json [junit4] 2> 3 /solr/collections/tlog_replica_test_delete_by_id/state.json [junit4] 2> 2 /solr/collections/tlog_replica_test_kill_tlog_replica/leader_elect/shard1/election/72187927430692870-core_node4-n_0000000000 [junit4] 2> 2 /solr/collections/tlog_replica_test_create_delete/leader_elect/shard2/election/72187927430692868-core_node11-n_0000000002 [junit4] 2> 2 /solr/autoscaling.json [junit4] 2> 2 /solr/collections/tlog_replica_test_recovery/leader_elect/shard1/election/72187927430692870-core_node4-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 15 /solr/live_nodes [junit4] 2> 15 /solr/collections [junit4] 2> 2 /solr/overseer/queue [junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 2 /solr/overseer/queue-work [junit4] 2> 2 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_D3B120673FBD433A-002 [junit4] 2> Nov 19, 2018 8:02:44 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {}, locale=en-150, timezone=Asia/Nicosia [junit4] 2> NOTE: Linux 4.15.0-38-generic amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=8,threads=1,free=236280856,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestTlogReplica, TestTlogReplica] [junit4] Completed [4/5 (4!)] on J0 in 88.33s, 13 tests, 1 failure <<< FAILURES! [...truncated 30 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, 4 failures [seed: D3B120673FBD433A] Total time: 3 minutes 44 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 4/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=b306b4cc53c2436f8f6fd29d9f2e0de705b1876e, workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux [WARNINGS] Computing warning deltas based on reference build #3117 Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
