Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-7.x/34/
2 tests failed. FAILED: org.apache.solr.cloud.TestTlogReplica.testAddDocs Error Message: Could not load collection from ZK: tlog_replica_test_add_docs Stack Trace: org.apache.solr.common.SolrException: Could not load collection from ZK: tlog_replica_test_add_docs at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1250) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:679) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131) at org.apache.solr.cloud.TestTlogReplica.tearDown(TestTlogReplica.java:122) at sun.reflect.GeneratedMethodAccessor27.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.lang.Thread.run(Thread.java:748) Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/tlog_replica_test_add_docs/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1262) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1248) ... 40 more FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream Error Message: Could not load collection from ZK: checkpointCollection Stack Trace: org.apache.solr.common.SolrException: Could not load collection from ZK: checkpointCollection at __randomizedtesting.SeedInfo.seed([84C8BF259A5075A8:2180251DA3086C3C]:0) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1250) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:679) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:148) at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:131) at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154) at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3224) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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 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.lang.Thread.run(Thread.java:748) Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/checkpointCollection/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1215) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:340) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1262) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1248) ... 44 more Build Log: [...truncated 12504 lines...] [junit4] Suite: org.apache.solr.cloud.TestTlogReplica [junit4] 2> 1110851 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-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/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/init-core-data-001 [junit4] 2> 1110851 WARN (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 1111032 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1111033 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1111034 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001 [junit4] 2> 1111084 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1111088 INFO (Thread-660) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1111088 INFO (Thread-660) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1111088 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36675 [junit4] 2> 1111260 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer not running [junit4] 2> 1111348 ERROR (Thread-660) [ ] 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> 1112434 INFO (zkConnectionManagerCallback-821-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112489 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T07:57:37+10:30, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1112530 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T07:57:37+10:30, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1112561 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1112562 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1112562 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1112562 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@20490284{/solr,null,AVAILABLE} [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2ad66a19{SSL,[ssl, http/1.1]}{127.0.0.1:44962} [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.e.j.s.Server Started @1112696ms [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44962} [junit4] 2> 1112564 ERROR (jetty-launcher-818-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1112564 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-07T00:03:07.263Z [junit4] 2> 1112654 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1112654 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1112654 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1112655 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8ae00ff{/solr,null,AVAILABLE} [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@40f28cb9{SSL,[ssl, http/1.1]}{127.0.0.1:33737} [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.e.j.s.Server Started @1112803ms [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33737} [junit4] 2> 1112671 ERROR (jetty-launcher-818-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0 [junit4] 2> 1112671 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1112672 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1112672 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-07T00:03:07.371Z [junit4] 2> 1112707 INFO (zkConnectionManagerCallback-825-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112723 INFO (zkConnectionManagerCallback-823-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112723 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1112760 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1112793 INFO (jetty-launcher-818-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36675/solr [junit4] 2> 1112815 INFO (jetty-launcher-818-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36675/solr [junit4] 2> 1112835 INFO (zkConnectionManagerCallback-829-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112835 INFO (zkConnectionManagerCallback-833-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112886 INFO (zkConnectionManagerCallback-835-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1112991 INFO (zkConnectionManagerCallback-837-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1113104 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1113158 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44962_solr [junit4] 2> 1113159 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.Overseer Overseer (id=72411277853720582-127.0.0.1:44962_solr-n_0000000000) starting [junit4] 2> 1113381 INFO (zkConnectionManagerCallback-846-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1113620 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36675/solr ready [junit4] 2> 1113658 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44962_solr [junit4] 2> 1113742 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1113815 INFO (zkCallback-845-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1114145 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1114146 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1114161 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1114161 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33737_solr [junit4] 2> 1114180 INFO (zkCallback-845-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1114184 INFO (zkCallback-836-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1114249 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1114331 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114424 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114424 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114425 INFO (jetty-launcher-818-thread-1) [n:127.0.0.1:44962_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/. [junit4] 2> 1114683 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114710 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114710 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1114711 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/. [junit4] 2> 1114757 INFO (zkConnectionManagerCallback-851-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1114758 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1114759 INFO (jetty-launcher-818-thread-2) [n:127.0.0.1:33737_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36675/solr ready [junit4] 2> 1114830 INFO (zkConnectionManagerCallback-853-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1114846 INFO (zkConnectionManagerCallback-858-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1114847 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1114847 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36675/solr ready [junit4] 2> 1114858 INFO (SUITE-TestTlogReplica-seed#[E4372ED05606DCA9]-worker) [ ] o.a.s.c.TestTlogReplica Using legacyCloud?: false [junit4] 2> 1115083 INFO (qtp849419614-4056) [n:127.0.0.1:44962_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> 1115084 INFO (qtp849419614-4056) [n:127.0.0.1:44962_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> 1115541 INFO (TEST-TestTlogReplica.testCreateDelete-seed#[E4372ED05606DCA9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateDelete {seed=[E4372ED05606DCA9:FF27DF9CA0F62499]} [junit4] 2> 1115847 INFO (qtp373196700-4065) [n:127.0.0.1:33737_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_create_delete&action=CREATE&numShards=2&tlogReplicas=4 and sendToOCPQueue=true [junit4] 2> 1115850 INFO (OverseerThreadFactory-1428-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection tlog_replica_test_create_delete [junit4] 2> 1115851 WARN (OverseerThreadFactory-1428-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 4 on collection tlog_replica_test_create_delete 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> 1116097 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:33737/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116120 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:44962/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116137 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:33737/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116171 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"tlog_replica_test_create_delete_shard1_replica_t7", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:44962/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116174 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t8", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:33737/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116176 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t10", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:44962/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116191 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t12", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:33737/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116203 INFO (OverseerStateUpdate-72411277853720582-127.0.0.1:44962_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"tlog_replica_test_create_delete", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"tlog_replica_test_create_delete_shard2_replica_t14", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:44962/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1116608 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t2&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116608 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1116624 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t1&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116625 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t4&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116660 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t8&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116668 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t12&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116669 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node16&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t14&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116670 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard1_replica_t7&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard1&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116694 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=tlog_replica_test_create_delete_shard2_replica_t10&action=CREATE&numShards=2&collection=tlog_replica_test_create_delete&shard=shard2&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 1116847 INFO (zkCallback-836-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1116847 INFO (zkCallback-836-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1116851 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1117733 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1117836 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1117924 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1117940 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t8] Schema name=minimal [junit4] 2> 1117961 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1117978 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t2] Schema name=minimal [junit4] 2> 1117942 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1117992 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t8' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1117993 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.core.tlog_replica_test_create_delete.shard2.replica_t8' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t8') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1117993 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1117993 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t8] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/tlog_replica_test_create_delete_shard2_replica_t8], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard2_replica_t8/data/] [junit4] 2> 1118005 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t1] Schema name=minimal [junit4] 2> 1118015 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118015 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t2' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118029 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.core.tlog_replica_test_create_delete.shard1.replica_t2' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118029 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118029 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/tlog_replica_test_create_delete_shard1_replica_t2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard1_replica_t2/data/] [junit4] 2> 1118040 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118040 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t1' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118040 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.core.tlog_replica_test_create_delete.shard1.replica_t1' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118041 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118041 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/tlog_replica_test_create_delete_shard1_replica_t1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard1_replica_t1/data/] [junit4] 2> 1118062 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1118078 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1118094 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t10] Schema name=minimal [junit4] 2> 1118176 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1118220 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t7] Schema name=minimal [junit4] 2> 1118223 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118223 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t10' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118224 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.core.tlog_replica_test_create_delete.shard2.replica_t10' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118224 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118224 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t10] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/tlog_replica_test_create_delete_shard2_replica_t10], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard2_replica_t10/data/] [junit4] 2> 1118235 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118235 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t7' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118235 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.core.tlog_replica_test_create_delete.shard1.replica_t7' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t7') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118341 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118341 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/tlog_replica_test_create_delete_shard1_replica_t7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard1_replica_t7/data/] [junit4] 2> 1118357 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0 [junit4] 2> 1118358 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t14] Schema name=minimal [junit4] 2> 1118390 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118390 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t14' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118391 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44962.solr.core.tlog_replica_test_create_delete.shard2.replica_t14' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t14') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118391 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118391 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t14] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/tlog_replica_test_create_delete_shard2_replica_t14], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node1/./tlog_replica_test_create_delete_shard2_replica_t14/data/] [junit4] 2> 1118425 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard1_replica_t4] Schema name=minimal [junit4] 2> 1118448 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.s.IndexSchema [tlog_replica_test_create_delete_shard2_replica_t12] Schema name=minimal [junit4] 2> 1118483 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118483 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard2_replica_t12' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118484 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.core.tlog_replica_test_create_delete.shard2.replica_t12' (registry 'solr.core.tlog_replica_test_create_delete.shard2.replica_t12') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118484 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1118484 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118484 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.CoreContainer Creating SolrCore 'tlog_replica_test_create_delete_shard1_replica_t4' using configuration from collection tlog_replica_test_create_delete, trusted=true [junit4] 2> 1118484 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard2_replica_t12] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/tlog_replica_test_create_delete_shard2_replica_t12], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard2_replica_t12/data/] [junit4] 2> 1118485 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33737.solr.core.tlog_replica_test_create_delete.shard1.replica_t4' (registry 'solr.core.tlog_replica_test_create_delete.shard1.replica_t4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e6bfab1 [junit4] 2> 1118485 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1118485 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrCore [[tlog_replica_test_create_delete_shard1_replica_t4] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/tlog_replica_test_create_delete_shard1_replica_t4], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.TestTlogReplica_E4372ED05606DCA9-001/tempDir-001/node2/./tlog_replica_test_create_delete_shard1_replica_t4/data/] [junit4] 2> 1119212 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119212 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119233 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119233 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119290 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7deaca39[tlog_replica_test_create_delete_shard1_replica_t2] main] [junit4] 2> 1119326 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119326 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119342 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119342 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527165935616 [junit4] 2> 1119477 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119477 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119478 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119478 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119508 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119508 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119510 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.s.SolrIndexSearcher Opening [Searcher@46509ca8[tlog_replica_test_create_delete_shard1_replica_t1] main] [junit4] 2> 1119533 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119533 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119534 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d716d09[tlog_replica_test_create_delete_shard1_replica_t7] main] [junit4] 2> 1119541 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119541 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119542 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119542 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119542 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119542 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119543 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119543 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.s.SolrIndexSearcher Opening [Searcher@62e85dd9[tlog_replica_test_create_delete_shard2_replica_t12] main] [junit4] 2> 1119543 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527376699392 [junit4] 2> 1119638 INFO (searcherExecutor-1434-thread-1-processing-n:127.0.0.1:44962_solr x:tlog_replica_test_create_delete_shard1_replica_t2 c:tlog_replica_test_create_delete s:shard1 r:core_node5) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard1_replica_t2] Registered new searcher Searcher@7deaca39[tlog_replica_test_create_delete_shard1_replica_t2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119652 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119653 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119653 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119653 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527492042752 [junit4] 2> 1119723 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119723 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119724 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119725 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119725 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119725 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard1 to Terms{values={core_node5=0}, version=0} [junit4] 2> 1119725 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119726 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119726 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.s.SolrIndexSearcher Opening [Searcher@21d50126[tlog_replica_test_create_delete_shard2_replica_t10] main] [junit4] 2> 1119741 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119741 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119742 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119743 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119743 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119745 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119745 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119761 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=4 found=1 timeoutin=9999ms [junit4] 2> 1119762 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1119762 INFO (zkCallback-836-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1119762 INFO (zkCallback-836-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1119764 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.s.SolrIndexSearcher Opening [Searcher@244014e0[tlog_replica_test_create_delete_shard2_replica_t8] main] [junit4] 2> 1119776 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119776 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119777 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119796 INFO (searcherExecutor-1437-thread-1-processing-n:127.0.0.1:44962_solr x:tlog_replica_test_create_delete_shard1_replica_t7 c:tlog_replica_test_create_delete s:shard1 r:core_node9) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard1_replica_t7] Registered new searcher Searcher@7d716d09[tlog_replica_test_create_delete_shard1_replica_t7] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119797 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527643037696 [junit4] 2> 1119816 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119816 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119833 INFO (searcherExecutor-1435-thread-1-processing-n:127.0.0.1:33737_solr x:tlog_replica_test_create_delete_shard1_replica_t1 c:tlog_replica_test_create_delete s:shard1 r:core_node3) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard1_replica_t1] Registered new searcher Searcher@46509ca8[tlog_replica_test_create_delete_shard1_replica_t1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119834 INFO (qtp849419614-4051) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard1 to Terms{values={core_node5=0, core_node9=0}, version=1} [junit4] 2> 1119834 INFO (searcherExecutor-1439-thread-1-processing-n:127.0.0.1:33737_solr x:tlog_replica_test_create_delete_shard2_replica_t12 c:tlog_replica_test_create_delete s:shard2 r:core_node15) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard2_replica_t12] Registered new searcher Searcher@62e85dd9[tlog_replica_test_create_delete_shard2_replica_t12] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119836 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119836 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119840 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527688126464 [junit4] 2> 1119870 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1119870 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1119871 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1119871 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1119873 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.s.SolrIndexSearcher Opening [Searcher@23a46557[tlog_replica_test_create_delete_shard2_replica_t14] main] [junit4] 2> 1119906 INFO (searcherExecutor-1436-thread-1-processing-n:127.0.0.1:44962_solr x:tlog_replica_test_create_delete_shard2_replica_t10 c:tlog_replica_test_create_delete s:shard2 r:core_node13) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard2_replica_t10] Registered new searcher Searcher@21d50126[tlog_replica_test_create_delete_shard2_replica_t10] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119906 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527757332480 [junit4] 2> 1119907 INFO (searcherExecutor-1433-thread-1-processing-n:127.0.0.1:33737_solr x:tlog_replica_test_create_delete_shard2_replica_t8 c:tlog_replica_test_create_delete s:shard2 r:core_node11) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard2_replica_t8] Registered new searcher Searcher@244014e0[tlog_replica_test_create_delete_shard2_replica_t8] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119918 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f738f17[tlog_replica_test_create_delete_shard1_replica_t4] main] [junit4] 2> 1119923 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119923 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119923 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard2 to Terms{values={core_node15=0}, version=0} [junit4] 2> 1119924 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119925 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1119958 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1119959 INFO (searcherExecutor-1440-thread-1-processing-n:127.0.0.1:33737_solr x:tlog_replica_test_create_delete_shard1_replica_t4 c:tlog_replica_test_create_delete s:shard1 r:core_node6) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard1_replica_t4] Registered new searcher Searcher@7f738f17[tlog_replica_test_create_delete_shard1_replica_t4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1119959 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1119959 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527812907008 [junit4] 2> 1119959 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597043527812907008 [junit4] 2> 1119961 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 1119976 INFO (qtp373196700-4061) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard1 to Terms{values={core_node3=0, core_node5=0, core_node9=0}, version=2} [junit4] 2> 1119978 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=4 found=1 timeoutin=9999ms [junit4] 2> 1120028 INFO (searcherExecutor-1438-thread-1-processing-n:127.0.0.1:44962_solr x:tlog_replica_test_create_delete_shard2_replica_t14 c:tlog_replica_test_create_delete s:shard2 r:core_node16) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.SolrCore [tlog_replica_test_create_delete_shard2_replica_t14] Registered new searcher Searcher@23a46557[tlog_replica_test_create_delete_shard2_replica_t14] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1120032 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 1120032 INFO (qtp849419614-4056) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node13 x:tlog_replica_test_create_delete_shard2_replica_t10] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard2 to Terms{values={core_node13=0, core_node15=0}, version=1} [junit4] 2> 1120032 INFO (qtp373196700-4066) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard1 to Terms{values={core_node6=0, core_node9=0, core_node3=0, core_node5=0}, version=3} [junit4] 2> 1120033 INFO (qtp373196700-4063) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node11 x:tlog_replica_test_create_delete_shard2_replica_t8] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard2 to Terms{values={core_node13=0, core_node15=0, core_node11=0}, version=2} [junit4] 2> 1120033 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 1120035 INFO (qtp849419614-4055) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard2 r:core_node16 x:tlog_replica_test_create_delete_shard2_replica_t14] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tlog_replica_test_create_delete/terms/shard2 to Terms{values={core_node11=0, core_node13=0, core_node16=0, core_node15=0}, version=3} [junit4] 2> 1120137 INFO (zkCallback-834-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1120137 INFO (zkCallback-836-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1120138 INFO (zkCallback-836-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tlog_replica_test_create_delete/state.json] for collection [tlog_replica_test_create_delete] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1120262 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1120262 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1120262 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44962/solr/tlog_replica_test_create_delete_shard1_replica_t2/ [junit4] 2> 1120263 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_create_delete_shard1_replica_t2 url=https://127.0.0.1:44962/solr START replicas=[https://127.0.0.1:33737/solr/tlog_replica_test_create_delete_shard1_replica_t1/, https://127.0.0.1:33737/solr/tlog_replica_test_create_delete_shard1_replica_t4/, https://127.0.0.1:44962/solr/tlog_replica_test_create_delete_shard1_replica_t7/] nUpdates=100 [junit4] 2> 1120375 INFO (qtp373196700-4067) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node3 x:tlog_replica_test_create_delete_shard1_replica_t1] o.a.s.c.S.Request [tlog_replica_test_create_delete_shard1_replica_t1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=30 [junit4] 2> 1120432 INFO (qtp849419614-4054) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node9 x:tlog_replica_test_create_delete_shard1_replica_t7] o.a.s.c.S.Request [tlog_replica_test_create_delete_shard1_replica_t7] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=18 [junit4] 2> 1120434 INFO (qtp373196700-4166) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard1 r:core_node6 x:tlog_replica_test_create_delete_shard1_replica_t4] o.a.s.c.S.Request [tlog_replica_test_create_delete_shard1_replica_t4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1120481 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1120481 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1120481 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33737/solr/tlog_replica_test_create_delete_shard2_replica_t12/ [junit4] 2> 1120482 INFO (qtp373196700-4064) [n:127.0.0.1:33737_solr c:tlog_replica_test_create_delete s:shard2 r:core_node15 x:tlog_replica_test_create_delete_shard2_replica_t12] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_create_delete_shard2_replica_t12 url=https://127.0.0.1:33737/solr START replicas=[https://127.0.0.1:33737/solr/tlog_replica_test_create_delete_shard2_replica_t8/, https://127.0.0.1:44962/solr/tlog_replica_test_create_delete_shard2_replica_t10/, https://127.0.0.1:44962/solr/tlog_replica_test_create_delete_shard2_replica_t14/] nUpdates=100 [junit4] 2> 1120627 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.u.PeerSync PeerSync: core=tlog_replica_test_create_delete_shard1_replica_t2 url=https://127.0.0.1:44962/solr DONE. We have no versions. sync failed. [junit4] 2> 1120627 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c:tlog_replica_test_create_delete s:shard1 r:core_node5 x:tlog_replica_test_create_delete_shard1_replica_t2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 1120627 INFO (qtp849419614-4057) [n:127.0.0.1:44962_solr c [...truncated too long message...] ure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/null892350982 [copy] Copying 40 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/null892350982 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/null892350982 resolve-example: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: resolve-server: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null2112341427 [copy] Copying 246 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null2112341427 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-7.x/solr/null2112341427 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 48ms :: artifacts dl 1ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 225 minutes 33 seconds Archiving artifacts WARN: No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? WARN: java.lang.InterruptedException: no matches found within 10000 Recording test results 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org