Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/106/ Java: 64bit/jdk1.8.0_172 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest.testSimple Error Message: Error from server at https://127.0.0.1:38047/solr: org.apache.solr.common.SolrException: Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException: Error from server at https://127.0.0.1:38047/solr: org.apache.solr.common.SolrException: at __randomizedtesting.SeedInfo.seed([90E4826FAD43DF15:A857A6918AB00BC4]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException.create(HttpSolrClient.java:829) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1222) at org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest.testSimple(AutoAddReplicasPlanActionTest.java:118) 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:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at 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) FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testNodeMarkersRegistration Error Message: Path /autoscaling/nodeAdded/127.0.0.1:10018_solr should have been deleted Stack Trace: java.lang.AssertionError: Path /autoscaling/nodeAdded/127.0.0.1:10018_solr should have been deleted at __randomizedtesting.SeedInfo.seed([90E4826FAD43DF15:885E0A63A37612FA]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration.testNodeMarkersRegistration(TestSimTriggerIntegration.java:889) 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:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at 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) Build Log: [...truncated 14082 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/init-core-data-001 [junit4] 2> 2145755 WARN (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=29 numCloses=29 [junit4] 2> 2145755 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2145756 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-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> 2145756 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2145757 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001 [junit4] 2> 2145757 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2145757 INFO (Thread-6211) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2145757 INFO (Thread-6211) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2145758 ERROR (Thread-6211) [ ] 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> 2145857 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:35509 [junit4] 2> 2145859 INFO (zkConnectionManagerCallback-9876-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145864 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 2145864 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 2145864 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 2145872 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2b1d6968{/solr,null,AVAILABLE} [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ecab91e{/solr,null,AVAILABLE} [junit4] 2> 2145873 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@151725ef{/solr,null,AVAILABLE} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@651a5e18{SSL,[ssl, http/1.1]}{127.0.0.1:34945} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47201ccf{SSL,[ssl, http/1.1]}{127.0.0.1:38047} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-3) [ ] o.e.j.s.Server Started @2145908ms [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2516907a{SSL,[ssl, http/1.1]}{127.0.0.1:43163} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-1) [ ] o.e.j.s.Server Started @2145908ms [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34945} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.e.j.s.Server Started @2145908ms [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38047} [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43163} [junit4] 2> 2145874 ERROR (jetty-launcher-9873-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2145874 ERROR (jetty-launcher-9873-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2145874 ERROR (jetty-launcher-9873-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2145874 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-14T04:55:06.918Z [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-14T04:55:06.918Z [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2145875 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-10-14T04:55:06.918Z [junit4] 2> 2145878 INFO (zkConnectionManagerCallback-9879-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145878 INFO (zkConnectionManagerCallback-9882-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145878 INFO (zkConnectionManagerCallback-9880-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145878 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2145878 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2145879 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2145942 INFO (jetty-launcher-9873-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35509/solr [junit4] 2> 2145942 INFO (jetty-launcher-9873-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35509/solr [junit4] 2> 2145943 INFO (zkConnectionManagerCallback-9890-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145943 INFO (zkConnectionManagerCallback-9888-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145944 INFO (zkConnectionManagerCallback-9892-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145944 INFO (zkConnectionManagerCallback-9894-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145966 INFO (jetty-launcher-9873-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35509/solr [junit4] 2> 2145967 INFO (zkConnectionManagerCallback-9902-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145968 INFO (zkConnectionManagerCallback-9904-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145972 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38047_solr [junit4] 2> 2145972 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.Overseer Overseer (id=72092882824331273-127.0.0.1:38047_solr-n_0000000000) starting [junit4] 2> 2145974 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34945_solr [junit4] 2> 2145974 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43163_solr [junit4] 2> 2145974 INFO (zkCallback-9903-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2145974 INFO (zkCallback-9891-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2145974 INFO (zkCallback-9893-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2145975 INFO (zkConnectionManagerCallback-9911-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2145975 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2145975 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35509/solr ready [junit4] 2> 2145976 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38047_solr [junit4] 2> 2145976 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2145976 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38047_solr [junit4] 2> 2145977 INFO (zkCallback-9903-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2145977 INFO (zkCallback-9893-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2145977 INFO (zkCallback-9891-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2145977 INFO (zkCallback-9910-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2146000 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2146002 INFO (zkConnectionManagerCallback-9922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2146002 INFO (zkConnectionManagerCallback-9917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2146002 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2146002 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2146002 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35509/solr ready [junit4] 2> 2146002 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35509/solr ready [junit4] 2> 2146003 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2146005 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2146021 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34945.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146023 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146025 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146027 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34945.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146028 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34945.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146028 INFO (jetty-launcher-9873-thread-3) [n:127.0.0.1:34945_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node3/. [junit4] 2> 2146032 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146032 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146032 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146032 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2146033 INFO (jetty-launcher-9873-thread-1) [n:127.0.0.1:38047_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/. [junit4] 2> 2146033 INFO (jetty-launcher-9873-thread-2) [n:127.0.0.1:43163_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/. [junit4] 2> 2146088 INFO (zkConnectionManagerCallback-9926-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2146090 INFO (zkConnectionManagerCallback-9931-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2146091 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2146091 INFO (SUITE-AutoAddReplicasPlanActionTest-seed#[90E4826FAD43DF15]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35509/solr ready [junit4] 2> 2146105 INFO (qtp1311515933-29553) [n:127.0.0.1:34945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster params={wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 2146124 INFO (TEST-AutoAddReplicasPlanActionTest.testSimple-seed#[90E4826FAD43DF15]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSimple [junit4] 2> 2146133 INFO (qtp2019536154-29547) [n:127.0.0.1:38047_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=2&autoAddReplicas=true&name=testSimple1&nrtReplicas=2&action=CREATE&numShards=2&createNodeSet=127.0.0.1:43163_solr,127.0.0.1:38047_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2146135 INFO (OverseerThreadFactory-7646-thread-1-processing-n:127.0.0.1:38047_solr) [n:127.0.0.1:38047_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testSimple1 [junit4] 2> 2146238 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43163/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2146239 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple1_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38047/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2146240 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43163/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2146241 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple1_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38047/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2146447 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr x:testSimple1_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n6&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2146447 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr x:testSimple1_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n2&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2146462 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr x:testSimple1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n1&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2146462 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr x:testSimple1_shard2_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2146462 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr x:testSimple1_shard2_replica_n4] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2147457 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2147462 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.s.IndexSchema [testSimple1_shard1_replica_n2] Schema name=minimal [junit4] 2> 2147479 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2147479 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2147479 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard1_replica_n2' using configuration from collection testSimple1, trusted=true [junit4] 2> 2147480 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.core.testSimple1.shard1.replica_n2' (registry 'solr.core.testSimple1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2147480 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.SolrCore [[testSimple1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/testSimple1_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/./testSimple1_shard1_replica_n2/data/] [junit4] 2> 2147484 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.s.IndexSchema [testSimple1_shard2_replica_n6] Schema name=minimal [junit4] 2> 2147486 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2147486 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2147486 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard2_replica_n6' using configuration from collection testSimple1, trusted=true [junit4] 2> 2147486 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2147486 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.core.testSimple1.shard2.replica_n6' (registry 'solr.core.testSimple1.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2147486 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.SolrCore [[testSimple1_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/testSimple1_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/./testSimple1_shard2_replica_n6/data/] [junit4] 2> 2147492 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.s.IndexSchema [testSimple1_shard1_replica_n1] Schema name=minimal [junit4] 2> 2147492 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.s.IndexSchema [testSimple1_shard2_replica_n4] Schema name=minimal [junit4] 2> 2147493 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2147493 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2147493 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard2_replica_n4' using configuration from collection testSimple1, trusted=true [junit4] 2> 2147493 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testSimple1_shard1_replica_n1' using configuration from collection testSimple1, trusted=true [junit4] 2> 2147494 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.core.testSimple1.shard1.replica_n1' (registry 'solr.core.testSimple1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2147494 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.core.testSimple1.shard2.replica_n4' (registry 'solr.core.testSimple1.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2147494 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.SolrCore [[testSimple1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/testSimple1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/./testSimple1_shard1_replica_n1/data/] [junit4] 2> 2147494 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.SolrCore [[testSimple1_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/testSimple1_shard2_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/./testSimple1_shard2_replica_n4/data/] [junit4] 2> 2147527 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2147527 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2147528 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2147528 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2147528 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2147528 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2147529 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ac198c6[testSimple1_shard2_replica_n6] main] [junit4] 2> 2147529 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2147529 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2147530 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2147530 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2147530 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@16f6b7b6[testSimple1_shard1_replica_n2] main] [junit4] 2> 2147530 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2147531 INFO (searcherExecutor-7660-thread-1-processing-n:127.0.0.1:38047_solr x:testSimple1_shard2_replica_n6 c:testSimple1 s:shard2 r:core_node8) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.SolrCore [testSimple1_shard2_replica_n6] Registered new searcher Searcher@3ac198c6[testSimple1_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2147531 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2147531 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275316100890624 [junit4] 2> 2147531 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2147531 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2147531 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275316100890624 [junit4] 2> 2147532 INFO (searcherExecutor-7659-thread-1-processing-n:127.0.0.1:38047_solr x:testSimple1_shard1_replica_n2 c:testSimple1 s:shard1 r:core_node5) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.SolrCore [testSimple1_shard1_replica_n2] Registered new searcher Searcher@16f6b7b6[testSimple1_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2147533 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2147533 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2147534 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2147534 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2147534 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple1/terms/shard2 to Terms{values={core_node8=0}, version=0} [junit4] 2> 2147535 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple1/terms/shard1 to Terms{values={core_node5=0}, version=0} [junit4] 2> 2147535 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c6c4610[testSimple1_shard1_replica_n1] main] [junit4] 2> 2147535 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2147535 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2147536 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2147536 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9999ms [junit4] 2> 2147536 INFO (searcherExecutor-7662-thread-1-processing-n:127.0.0.1:43163_solr x:testSimple1_shard1_replica_n1 c:testSimple1 s:shard1 r:core_node3) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.SolrCore [testSimple1_shard1_replica_n1] Registered new searcher Searcher@c6c4610[testSimple1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2147536 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275316106133504 [junit4] 2> 2147536 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 2147537 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2147537 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2147538 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2147538 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2147539 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple1/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1} [junit4] 2> 2147539 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@a582f82[testSimple1_shard2_replica_n4] main] [junit4] 2> 2147540 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2147540 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2147540 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2147540 INFO (searcherExecutor-7661-thread-1-processing-n:127.0.0.1:43163_solr x:testSimple1_shard2_replica_n4 c:testSimple1 s:shard2 r:core_node7) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.SolrCore [testSimple1_shard2_replica_n4] Registered new searcher Searcher@a582f82[testSimple1_shard2_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2147540 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275316110327808 [junit4] 2> 2147543 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple1/terms/shard2 to Terms{values={core_node7=0, core_node8=0}, version=1} [junit4] 2> 2148037 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2148037 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2148037 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38047/solr/testSimple1_shard2_replica_n6/ [junit4] 2> 2148037 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2148037 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2148037 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38047/solr/testSimple1_shard1_replica_n2/ [junit4] 2> 2148037 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=testSimple1_shard2_replica_n6 url=https://127.0.0.1:38047/solr START replicas=[https://127.0.0.1:43163/solr/testSimple1_shard2_replica_n4/] nUpdates=100 [junit4] 2> 2148037 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=testSimple1_shard1_replica_n2 url=https://127.0.0.1:38047/solr START replicas=[https://127.0.0.1:43163/solr/testSimple1_shard1_replica_n1/] nUpdates=100 [junit4] 2> 2148037 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=testSimple1_shard2_replica_n6 url=https://127.0.0.1:38047/solr DONE. We have no versions. sync failed. [junit4] 2> 2148038 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=testSimple1_shard1_replica_n2 url=https://127.0.0.1:38047/solr DONE. We have no versions. sync failed. [junit4] 2> 2148040 INFO (qtp1202188365-29550) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.c.S.Request [testSimple1_shard2_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2148040 INFO (qtp1202188365-29546) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.c.S.Request [testSimple1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2148040 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2148040 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2148040 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2148040 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2148041 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38047/solr/testSimple1_shard2_replica_n6/ shard2 [junit4] 2> 2148041 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38047/solr/testSimple1_shard1_replica_n2/ shard1 [junit4] 2> 2148143 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2148173 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple1 s:shard1 r:core_node5 x:testSimple1_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n2&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1726 [junit4] 2> 2148193 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2148195 INFO (qtp2019536154-29646) [n:127.0.0.1:38047_solr c:testSimple1 s:shard2 r:core_node8 x:testSimple1_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n6&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1748 [junit4] 2> 2148295 INFO (zkCallback-9903-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple1/state.json] for collection [testSimple1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2148541 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple1 s:shard1 r:core_node3 x:testSimple1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple1_shard1_replica_n1&action=CREATE&numShards=2&collection=testSimple1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2079 [junit4] 2> 2148545 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple1 s:shard2 r:core_node7 x:testSimple1_shard2_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testSimple1_shard2_replica_n4&action=CREATE&numShards=2&collection=testSimple1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2083 [junit4] 2> 2148547 INFO (qtp2019536154-29547) [n:127.0.0.1:38047_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 2148645 INFO (zkCallback-9903-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple1/state.json] for collection [testSimple1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2148645 INFO (zkCallback-9893-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple1/state.json] for collection [testSimple1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2149547 INFO (qtp2019536154-29547) [n:127.0.0.1:38047_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=2&autoAddReplicas=true&name=testSimple1&nrtReplicas=2&action=CREATE&numShards=2&createNodeSet=127.0.0.1:43163_solr,127.0.0.1:38047_solr&wt=javabin&version=2} status=0 QTime=3413 [junit4] 2> 2149548 INFO (qtp2019536154-29548) [n:127.0.0.1:38047_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&autoAddReplicas=false&name=testSimple2&nrtReplicas=2&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38047_solr,127.0.0.1:34945_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2149549 INFO (OverseerThreadFactory-7646-thread-2-processing-n:127.0.0.1:38047_solr) [n:127.0.0.1:38047_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testSimple2 [junit4] 2> 2149549 INFO (OverseerCollectionConfigSetProcessor-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2149754 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple2", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple2_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:38047/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2149754 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple2", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple2_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:34945/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2149958 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr x:testSimple2_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple2_shard1_replica_n1&action=CREATE&numShards=1&collection=testSimple2&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2149959 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr x:testSimple2_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testSimple2_shard1_replica_n2&action=CREATE&numShards=1&collection=testSimple2&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2149959 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr x:testSimple2_shard1_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2150966 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2150966 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2150970 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.s.IndexSchema [testSimple2_shard1_replica_n2] Schema name=minimal [junit4] 2> 2150970 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.s.IndexSchema [testSimple2_shard1_replica_n1] Schema name=minimal [junit4] 2> 2150971 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2150971 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2150971 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testSimple2_shard1_replica_n2' using configuration from collection testSimple2, trusted=true [junit4] 2> 2150971 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testSimple2_shard1_replica_n1' using configuration from collection testSimple2, trusted=true [junit4] 2> 2150972 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38047.solr.core.testSimple2.shard1.replica_n1' (registry 'solr.core.testSimple2.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2150972 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34945.solr.core.testSimple2.shard1.replica_n2' (registry 'solr.core.testSimple2.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2150972 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.SolrCore [[testSimple2_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/testSimple2_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node1/./testSimple2_shard1_replica_n1/data/] [junit4] 2> 2150972 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.SolrCore [[testSimple2_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node3/testSimple2_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node3/./testSimple2_shard1_replica_n2/data/] [junit4] 2> 2150997 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2150997 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2150997 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2150997 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2151024 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2151024 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2151024 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2151024 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2151025 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7d856d45[testSimple2_shard1_replica_n1] main] [junit4] 2> 2151025 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@12dc1ae9[testSimple2_shard1_replica_n2] main] [junit4] 2> 2151026 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2151026 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 2151026 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2151026 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 2151026 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2151026 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2151026 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275319765663744 [junit4] 2> 2151027 INFO (searcherExecutor-7679-thread-1-processing-n:127.0.0.1:34945_solr x:testSimple2_shard1_replica_n2 c:testSimple2 s:shard1 r:core_node4) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.SolrCore [testSimple2_shard1_replica_n2] Registered new searcher Searcher@12dc1ae9[testSimple2_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2151027 INFO (searcherExecutor-7680-thread-1-processing-n:127.0.0.1:38047_solr x:testSimple2_shard1_replica_n1 c:testSimple2 s:shard1 r:core_node3) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.SolrCore [testSimple2_shard1_replica_n1] Registered new searcher Searcher@7d856d45[testSimple2_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2151027 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1614275319766712320 [junit4] 2> 2151029 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple2/terms/shard1 to Terms{values={core_node4=0}, version=0} [junit4] 2> 2151029 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 2151030 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testSimple2/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1} [junit4] 2> 2151031 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 2151609 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2151609 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 2151609 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34945/solr/testSimple2_shard1_replica_n2/ [junit4] 2> 2151610 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=testSimple2_shard1_replica_n2 url=https://127.0.0.1:34945/solr START replicas=[https://127.0.0.1:38047/solr/testSimple2_shard1_replica_n1/] nUpdates=100 [junit4] 2> 2151610 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=testSimple2_shard1_replica_n2 url=https://127.0.0.1:34945/solr DONE. We have no versions. sync failed. [junit4] 2> 2151612 INFO (qtp2019536154-29552) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.c.S.Request [testSimple2_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2151612 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 2151612 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 2151614 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34945/solr/testSimple2_shard1_replica_n2/ shard1 [junit4] 2> 2151766 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2151768 INFO (qtp1311515933-29557) [n:127.0.0.1:34945_solr c:testSimple2 s:shard1 r:core_node4 x:testSimple2_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testSimple2_shard1_replica_n2&action=CREATE&numShards=1&collection=testSimple2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1808 [junit4] 2> 2151868 INFO (zkCallback-9891-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple2/state.json] for collection [testSimple2] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2152111 INFO (qtp2019536154-29543) [n:127.0.0.1:38047_solr c:testSimple2 s:shard1 r:core_node3 x:testSimple2_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple2_shard1_replica_n1&action=CREATE&numShards=1&collection=testSimple2&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2153 [junit4] 2> 2152113 INFO (qtp2019536154-29548) [n:127.0.0.1:38047_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 2152211 INFO (zkCallback-9903-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple2/state.json] for collection [testSimple2] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2152211 INFO (zkCallback-9891-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testSimple2/state.json] for collection [testSimple2] has occurred - updating... (live nodes size: [3]) [junit4] 2> 2153113 INFO (qtp2019536154-29548) [n:127.0.0.1:38047_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=1&autoAddReplicas=false&name=testSimple2&nrtReplicas=2&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38047_solr,127.0.0.1:34945_solr&wt=javabin&version=2} status=0 QTime=3565 [junit4] 2> 2153114 INFO (qtp2019536154-29547) [n:127.0.0.1:38047_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=3&autoAddReplicas=false&name=testSimple3&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=127.0.0.1:43163_solr&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2153115 INFO (OverseerThreadFactory-7646-thread-3-processing-n:127.0.0.1:38047_solr) [n:127.0.0.1:38047_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testSimple3 [junit4] 2> 2153115 INFO (OverseerCollectionConfigSetProcessor-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2153320 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple3", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"testSimple3_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43163/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2153321 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple3", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"testSimple3_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43163/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2153321 INFO (OverseerStateUpdate-72092882824331273-127.0.0.1:38047_solr-n_0000000000) [n:127.0.0.1:38047_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"testSimple3", [junit4] 2> "shard":"shard3", [junit4] 2> "core":"testSimple3_shard3_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:43163/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2153525 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr x:testSimple3_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testSimple3_shard2_replica_n2&action=CREATE&numShards=3&collection=testSimple3&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2153526 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr x:testSimple3_shard3_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=testSimple3_shard3_replica_n4&action=CREATE&numShards=3&collection=testSimple3&shard=shard3&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2153526 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr x:testSimple3_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testSimple3_shard1_replica_n1&action=CREATE&numShards=3&collection=testSimple3&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 2154534 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2154534 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2154535 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2154540 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.s.IndexSchema [testSimple3_shard3_replica_n4] Schema name=minimal [junit4] 2> 2154540 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.s.IndexSchema [testSimple3_shard1_replica_n1] Schema name=minimal [junit4] 2> 2154540 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.s.IndexSchema [testSimple3_shard2_replica_n2] Schema name=minimal [junit4] 2> 2154542 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2154542 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2154542 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'testSimple3_shard3_replica_n4' using configuration from collection testSimple3, trusted=true [junit4] 2> 2154542 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testSimple3_shard1_replica_n1' using configuration from collection testSimple3, trusted=true [junit4] 2> 2154542 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.core.testSimple3.shard3.replica_n4' (registry 'solr.core.testSimple3.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2154542 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.core.testSimple3.shard1.replica_n1' (registry 'solr.core.testSimple3.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2154542 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 2154542 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'testSimple3_shard2_replica_n2' using configuration from collection testSimple3, trusted=true [junit4] 2> 2154542 INFO (qtp1202188365-29649) [n:127.0.0.1:43163_solr c:testSimple3 s:shard1 r:core_node3 x:testSimple3_shard1_replica_n1] o.a.s.c.SolrCore [[testSimple3_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/testSimple3_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/./testSimple3_shard1_replica_n1/data/] [junit4] 2> 2154542 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.c.SolrCore [[testSimple3_shard3_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/testSimple3_shard3_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/./testSimple3_shard3_replica_n4/data/] [junit4] 2> 2154543 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43163.solr.core.testSimple3.shard2.replica_n2' (registry 'solr.core.testSimple3.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@46621fb3 [junit4] 2> 2154543 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.c.SolrCore [[testSimple3_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/testSimple3_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoAddReplicasPlanActionTest_90E4826FAD43DF15-001/tempDir-001/node2/./testSimple3_shard2_replica_n2/data/] [junit4] 2> 2154610 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2154610 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2154610 INFO (qtp1202188365-29556) [n:127.0.0.1:43163_solr c:testSimple3 s:shard3 r:core_node6 x:testSimple3_shard3_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2154610 INFO (qtp1202188365-29554) [n:127.0.0.1:43163_solr c:testSimple3 s:shard2 r:core_node5 x:testSimple3_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 [...truncated too long message...] Rate [junit4] OK 0.57s J1 | TestSimTriggerIntegration.testNodeAddedTrigger [junit4] OK 0.69s J1 | TestSimTriggerIntegration.testNodeMarkersRegistration [junit4] OK 0.65s J1 | TestSimTriggerIntegration.testCooldown [junit4] OK 0.67s J1 | TestSimTriggerIntegration.testTriggerThrottling [junit4] OK 0.48s J1 | TestSimTriggerIntegration.testNodeLostTriggerRestoreState [junit4] OK 0.51s J1 | TestSimTriggerIntegration.testNodeLostTrigger [junit4] OK 0.73s J1 | TestSimTriggerIntegration.testListeners [junit4] OK 0.95s J1 | TestSimTriggerIntegration.testEventFromRestoredState [junit4] OK 0.63s J1 | TestSimTriggerIntegration.testEventQueue [junit4] Completed [2/10] on J1 in 10.03s, 11 tests [junit4] [junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message. [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration [junit4] OK 0.84s J0 | TestSimTriggerIntegration.testNodeAddedTriggerRestoreState [junit4] OK 0.79s J0 | TestSimTriggerIntegration.testSearchRate [junit4] OK 0.55s J0 | TestSimTriggerIntegration.testNodeAddedTrigger [junit4] OK 0.69s J0 | TestSimTriggerIntegration.testNodeMarkersRegistration [junit4] OK 0.67s J0 | TestSimTriggerIntegration.testCooldown [junit4] OK 0.68s J0 | TestSimTriggerIntegration.testTriggerThrottling [junit4] OK 0.48s J0 | TestSimTriggerIntegration.testNodeLostTriggerRestoreState [junit4] OK 0.51s J0 | TestSimTriggerIntegration.testNodeLostTrigger [junit4] OK 0.73s J0 | TestSimTriggerIntegration.testListeners [junit4] OK 0.95s J0 | TestSimTriggerIntegration.testEventFromRestoredState [junit4] OK 0.63s J0 | TestSimTriggerIntegration.testEventQueue [junit4] Completed [3/10] on J0 in 10.14s, 11 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] OK 19.1s J2 | AutoAddReplicasPlanActionTest.testSimple [junit4] Completed [4/10] on J2 in 27.19s, 1 test [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] OK 22.5s J1 | AutoAddReplicasPlanActionTest.testSimple [junit4] Completed [5/10] on J1 in 30.80s, 1 test [junit4] [junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message. [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] OK 23.1s J0 | AutoAddReplicasPlanActionTest.testSimple [junit4] Completed [6/10] on J0 in 31.81s, 1 test [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration [junit4] OK 0.49s J0 | TestSimTriggerIntegration.testNodeAddedTriggerRestoreState [junit4] OK 0.70s J0 | TestSimTriggerIntegration.testSearchRate [junit4] OK 0.55s J0 | TestSimTriggerIntegration.testNodeAddedTrigger [junit4] OK 0.67s J0 | TestSimTriggerIntegration.testNodeMarkersRegistration [junit4] OK 0.61s J0 | TestSimTriggerIntegration.testCooldown [junit4] OK 0.65s J0 | TestSimTriggerIntegration.testTriggerThrottling [junit4] OK 0.47s J0 | TestSimTriggerIntegration.testNodeLostTriggerRestoreState [junit4] OK 0.51s J0 | TestSimTriggerIntegration.testNodeLostTrigger [junit4] OK 0.73s J0 | TestSimTriggerIntegration.testListeners [junit4] OK 0.95s J0 | TestSimTriggerIntegration.testEventFromRestoredState [junit4] OK 0.60s J0 | TestSimTriggerIntegration.testEventQueue [junit4] Completed [7/10] on J0 in 6.97s, 11 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration [junit4] OK 0.47s J0 | TestSimTriggerIntegration.testNodeAddedTriggerRestoreState [junit4] OK 0.66s J0 | TestSimTriggerIntegration.testSearchRate [junit4] OK 0.53s J0 | TestSimTriggerIntegration.testNodeAddedTrigger [junit4] OK 0.67s J0 | TestSimTriggerIntegration.testNodeMarkersRegistration [junit4] OK 0.66s J0 | TestSimTriggerIntegration.testCooldown [junit4] OK 0.65s J0 | TestSimTriggerIntegration.testTriggerThrottling [junit4] OK 0.47s J0 | TestSimTriggerIntegration.testNodeLostTriggerRestoreState [junit4] OK 0.50s J0 | TestSimTriggerIntegration.testNodeLostTrigger [junit4] OK 0.70s J0 | TestSimTriggerIntegration.testListeners [junit4] OK 0.95s J0 | TestSimTriggerIntegration.testEventFromRestoredState [junit4] OK 0.60s J0 | TestSimTriggerIntegration.testEventQueue [junit4] Completed [8/10] on J0 in 6.89s, 11 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] OK 17.0s J2 | AutoAddReplicasPlanActionTest.testSimple [junit4] Completed [9/10] on J2 in 20.78s, 1 test [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [junit4] OK 20.8s J1 | AutoAddReplicasPlanActionTest.testSimple [junit4] Completed [10/10] on J1 in 24.80s, 1 test [junit4] [junit4] JVM J0: 0.52 .. 57.32 = 56.80s [junit4] JVM J1: 0.51 .. 67.29 = 66.78s [junit4] JVM J2: 0.68 .. 59.31 = 58.63s [junit4] Execution time total: 1 minute 7 seconds [junit4] Tests summary: 10 suites, 60 tests [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J2 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J2/temp]) [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp]) [junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp]) [echo] 5 slowest tests: [junit4:tophints] 678.80s | org.apache.solr.cloud.cdcr.CdcrWithNodesRestartsTest [junit4:tophints] 476.97s | org.apache.solr.cloud.api.collections.ShardSplitTest [junit4:tophints] 474.96s | org.apache.solr.cloud.cdcr.CdcrOpsAndBoundariesTest [junit4:tophints] 175.06s | org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest [junit4:tophints] 116.83s | org.apache.solr.update.SoftAutoCommitTest -check-totals: test-nocompile: BUILD SUCCESSFUL Total time: 1 minute 9 seconds [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.AutoAddReplicasPlanActionTest [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimTriggerIntegration [repro] Exiting with code 0 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2845) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2724) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2705) at hudson.FilePath.act(FilePath.java:1076) at hudson.FilePath.act(FilePath.java:1059) at hudson.FilePath.validateAntFileMask(FilePath.java:2703) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1831) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error? [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=6d1b2e2f38a8979c65c1a208a0dd4cfc2de951ed, workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux [WARNINGS] Computing warning deltas based on reference build #105 Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
