Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2622/
1 tests failed. FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove Error Message: No live SolrServers available to handle this request:[https://127.0.0.1:45647/solr/MoveReplicaHDFSTest_failed_coll_true, https://127.0.0.1:47525/solr/MoveReplicaHDFSTest_failed_coll_true] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:45647/solr/MoveReplicaHDFSTest_failed_coll_true, https://127.0.0.1:47525/solr/MoveReplicaHDFSTest_failed_coll_true] at __randomizedtesting.SeedInfo.seed([29469F98CBD33C0C:838B4C6A7C00E9DC]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:462) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:993) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.cloud.MoveReplicaTest.testFailedMove(MoveReplicaTest.java:290) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:45647/solr/MoveReplicaHDFSTest_failed_coll_true: no servers hosting shard: shard1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) 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:436) ... 46 more Build Log: [...truncated 13120 lines...] [junit4] Suite: org.apache.solr.cloud.MoveReplicaHDFSTest [junit4] 2> 1053107 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/init-core-data-001 [junit4] 2> 1053108 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10 [junit4] 2> 1053135 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1053137 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-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> 1053138 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-001 [junit4] 2> 1053138 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1053139 INFO (Thread-1426) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1053139 INFO (Thread-1426) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1053171 ERROR (Thread-1426) [ ] 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> 1053243 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38677 [junit4] 2> 1053262 INFO (zkConnectionManagerCallback-2069-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1053287 INFO (jetty-launcher-2066-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> 1053294 INFO (jetty-launcher-2066-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> 1053295 INFO (jetty-launcher-2066-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> 1053305 INFO (jetty-launcher-2066-thread-4) [ ] 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> 1053344 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1053344 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1053344 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1053345 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@635c9e49{/solr,null,AVAILABLE} [junit4] 2> 1053346 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@20fe96be{SSL,[ssl, http/1.1]}{127.0.0.1:47525} [junit4] 2> 1053346 INFO (jetty-launcher-2066-thread-2) [ ] o.e.j.s.Server Started @1053417ms [junit4] 2> 1053346 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47525} [junit4] 2> 1053347 ERROR (jetty-launcher-2066-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1053395 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1053395 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1053395 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1053396 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8325def{/solr,null,AVAILABLE} [junit4] 2> 1053409 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1053409 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1053409 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1053409 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7073ae30{/solr,null,AVAILABLE} [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b1e269f{SSL,[ssl, http/1.1]}{127.0.0.1:50702} [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-3) [ ] o.e.j.s.Server Started @1053481ms [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50702} [junit4] 2> 1053410 ERROR (jetty-launcher-2066-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d432d3d{SSL,[ssl, http/1.1]}{127.0.0.1:39449} [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-1) [ ] o.e.j.s.Server Started @1053481ms [junit4] 2> 1053410 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39449} [junit4] 2> 1053410 ERROR (jetty-launcher-2066-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1053511 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1053511 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1053511 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1053511 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1053511 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-26T06:43:09.555Z [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-26T06:43:09.556Z [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1053512 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-26T06:43:09.556Z [junit4] 2> 1053524 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1053524 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1053524 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1053525 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fcb3d6c{/solr,null,AVAILABLE} [junit4] 2> 1053525 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2fac8ffe{SSL,[ssl, http/1.1]}{127.0.0.1:55119} [junit4] 2> 1053525 INFO (jetty-launcher-2066-thread-4) [ ] o.e.j.s.Server Started @1053596ms [junit4] 2> 1053525 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55119} [junit4] 2> 1053526 ERROR (jetty-launcher-2066-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1053526 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1053526 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1053526 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1053526 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1053526 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-26T06:43:09.570Z [junit4] 2> 1053575 INFO (zkConnectionManagerCallback-2073-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1053576 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1053585 INFO (zkConnectionManagerCallback-2075-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1053585 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1053589 INFO (zkConnectionManagerCallback-2077-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1053590 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1053604 INFO (zkConnectionManagerCallback-2071-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1053604 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1054025 INFO (jetty-launcher-2066-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38677/solr [junit4] 2> 1054035 INFO (zkConnectionManagerCallback-2081-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054036 DEBUG (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$40/2049103816@130ae424 [junit4] 2> 1054059 INFO (zkConnectionManagerCallback-2083-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054144 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1054145 DEBUG (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/74209749373288454-127.0.0.1:39449_solr-n_0000000000 [junit4] 2> 1054145 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39449_solr [junit4] 2> 1054146 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.Overseer Overseer (id=74209749373288454-127.0.0.1:39449_solr-n_0000000000) starting [junit4] 2> 1054191 INFO (zkConnectionManagerCallback-2090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054191 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054192 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054192 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054192 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054192 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054192 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38677/solr ready [junit4] 2> 1054195 INFO (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39449_solr [junit4] 2> 1054196 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue-work [junit4] 2> 1054197 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1054203 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1054205 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39449_solr [junit4] 2> 1054206 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054206 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054215 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 1054244 INFO (jetty-launcher-2066-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38677/solr [junit4] 2> 1054283 INFO (jetty-launcher-2066-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38677/solr [junit4] 2> 1054295 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054295 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/autoscaling.json [junit4] 2> 1054295 INFO (zkCallback-2082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1054295 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1054296 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1054296 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1054299 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1054318 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1054319 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054319 INFO (zkCallback-2089-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1054338 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1054367 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39449.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054368 INFO (zkConnectionManagerCallback-2094-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054385 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39449.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054385 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39449.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054386 INFO (jetty-launcher-2066-thread-1) [n:127.0.0.1:39449_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-001/node1/. [junit4] 2> 1054396 INFO (jetty-launcher-2066-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38677/solr [junit4] 2> 1054400 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 1054401 DEBUG (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$40/2049103816@2f0bb6a0 [junit4] 2> 1054415 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:39449_solr] [junit4] 2> 1054418 INFO (zkConnectionManagerCallback-2098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054418 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1054419 DEBUG (OverseerAutoScalingTriggerThread-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1054419 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 1054424 DEBUG (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$40/2049103816@61ec6647 [junit4] 2> 1054424 INFO (zkConnectionManagerCallback-2103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054426 DEBUG (ScheduledTrigger-3880-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 1054546 INFO (zkConnectionManagerCallback-2107-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054550 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054550 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054550 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1054551 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054551 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054551 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054551 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1054552 DEBUG (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/74209749373288459-127.0.0.1:50702_solr-n_0000000001 [junit4] 2> 1054552 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/74209749373288454-127.0.0.1:39449_solr-n_0000000000 [junit4] 2> 1054553 DEBUG (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/74209749373288454-127.0.0.1:39449_solr-n_0000000000 to know if I could be the leader [junit4] 2> 1054553 DEBUG (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50702_solr as DOWN [junit4] 2> 1054553 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1054554 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1054554 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50702_solr [junit4] 2> 1054554 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054554 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054554 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054580 DEBUG (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$40/2049103816@6410a671 [junit4] 2> 1054628 INFO (zkConnectionManagerCallback-2105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054631 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054631 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054631 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1054631 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054632 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054632 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054632 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1054633 DEBUG (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/74209749373288460-127.0.0.1:55119_solr-n_0000000002 [junit4] 2> 1054633 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/74209749373288459-127.0.0.1:50702_solr-n_0000000001 [junit4] 2> 1054633 DEBUG (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/74209749373288459-127.0.0.1:50702_solr-n_0000000001 to know if I could be the leader [junit4] 2> 1054633 DEBUG (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55119_solr as DOWN [junit4] 2> 1054634 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1054634 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55119_solr [junit4] 2> 1054634 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054652 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054652 INFO (zkCallback-2089-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1054653 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054653 INFO (zkCallback-2106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1054659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054659 INFO (zkCallback-2082-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1054660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054660 INFO (zkCallback-2104-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1054687 INFO (zkConnectionManagerCallback-2118-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054687 INFO (zkConnectionManagerCallback-2112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054690 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1054691 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:50702_solr"} current state version: 0 [junit4] 2> 1054691 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:50702_solr [junit4] 2> 1054691 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:55119_solr"} current state version: 0 [junit4] 2> 1054691 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:55119_solr [junit4] 2> 1054691 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054691 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054691 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1054692 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054692 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054692 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054693 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38677/solr ready [junit4] 2> 1054695 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054695 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054695 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1054695 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054695 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054695 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054696 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1054696 DEBUG (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/74209749373288462-127.0.0.1:47525_solr-n_0000000003 [junit4] 2> 1054697 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/74209749373288460-127.0.0.1:55119_solr-n_0000000002 [junit4] 2> 1054697 DEBUG (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/74209749373288460-127.0.0.1:55119_solr-n_0000000002 to know if I could be the leader [junit4] 2> 1054697 DEBUG (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:47525_solr as DOWN [junit4] 2> 1054697 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1054698 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:47525_solr"} current state version: 0 [junit4] 2> 1054698 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:47525_solr [junit4] 2> 1054698 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1054698 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47525_solr [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 1054699 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054699 INFO (zkCallback-2082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054699 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054701 INFO (zkCallback-2089-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054701 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1054727 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054727 INFO (zkCallback-2106-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054744 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054744 INFO (zkCallback-2111-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054776 INFO (zkConnectionManagerCallback-2131-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054777 INFO (zkCallback-2117-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054777 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1054777 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054778 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054778 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054778 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38677/solr ready [junit4] 2> 1054783 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054783 INFO (zkCallback-2104-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1054845 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1054845 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1054848 INFO (zkConnectionManagerCallback-2126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1054848 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1054849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1054849 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1054849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1054849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1054849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1054850 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38677/solr ready [junit4] 2> 1054853 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50702.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054867 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50702.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054867 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50702.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054868 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1054868 INFO (jetty-launcher-2066-thread-3) [n:127.0.0.1:50702_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-001/node3/. [junit4] 2> 1054881 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47525.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054900 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47525.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054900 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47525.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054915 INFO (jetty-launcher-2066-thread-2) [n:127.0.0.1:47525_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-001/node2/. [junit4] 2> 1054923 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1054935 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55119.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054941 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55119.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054941 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55119.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40fead95 [junit4] 2> 1054942 INFO (jetty-launcher-2066-thread-4) [n:127.0.0.1:55119_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-001/node4/. [junit4] 2> 1055248 INFO (zkConnectionManagerCallback-2135-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1055275 INFO (zkConnectionManagerCallback-2140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1055275 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1055275 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1055275 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1055275 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1055276 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1055276 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1055276 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38677/solr ready [junit4] 2> 1055327 INFO (qtp284297821-8095) [n:127.0.0.1:39449_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1055328 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1055328 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1055328 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 1055328 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 1055329 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1055329 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1055329 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 1055329 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 1055329 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 1055329 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running [junit4] 2> 1055329 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{"operation":"overseerstatus"} [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 1055339 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[] [junit4] 2> 1055339 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000 [junit4] 2> 1055339 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : overseerstatus , {"operation":"overseerstatus"} [junit4] 2> 1055341 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 1055341 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 1055341 DEBUG (zkCallback-2082-thread-1) [ ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000000 state SyncConnected latchEventType null [junit4] 2> 1055342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1055343 INFO (qtp284297821-8095) [n:127.0.0.1:39449_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 1055343 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1055344 INFO (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] 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> 1055344 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1055344 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1055345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1055345 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as completed. [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1055355 DEBUG (OverseerThreadFactory-3888-thread-1) [ ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Message id:/overseer/collection-queue-work/qn-0000000000 complete, response:{leader=127.0.0.1:39449_solr,overseer_queue_size=0,overseer_work_queue_size=0,overseer_collection_queue_size=2,overseer_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=2.619080191259204,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.2314483333333333,medianRequestTime=0.236931,75thPcRequestTime=0.330979,95thPcRequestTime=0.330979,99thPcRequestTime=0.330979,999thPcRequestTime=0.330979},downnode={requests=3,errors=0,avgRequestsPerSecond=4.616012172891856,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.03186866666666666,medianRequestTime=0.025294,75thPcRequestTime=0.048423,95thPcRequestTime=0.048423,99thPcRequestTime=0.048423,999thPcRequestTime=0.048423}},collection_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=2.637081933610898,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=4.688093319587214,medianRequestTime=1.965438,75thPcRequestTime=11.983073,95thPcRequestTime=11.983073,99thPcRequestTime=11.983073,999thPcRequestTime=11.983073},overseerstatus={requests=0,errors=0,avgRequestsPerSecond=0.0,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.0,medianRequestTime=0.0,75thPcRequestTime=0.0,95thPcRequestTime=0.0,99thPcRequestTime=0.0,999thPcRequestTime=0.0}},overseer_queue={},overseer_internal_queue={peek={avgRequestsPerSecond=0.8739206693024218,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.224303,medianRequestTime=0.224303,75thPcRequestTime=0.224303,95thPcRequestTime=0.224303,99thPcRequestTime=0.224303,999thPcRequestTime=0.224303}},collection_queue={peektopn_wait2000={avgRequestsPerSecond=0.9604031523497067,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=1029.922445,medianRequestTime=1029.922445,75thPcRequestTime=1029.922445,95thPcRequestTime=1029.922445,99thPcRequestTime=1029.922445,999thPcRequestTime=1029.922445}}} [junit4] 2> 1055666 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1056681 DEBUG (ScheduledTrigger-3880-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1056721 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 1057345 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1057346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1057681 DEBUG (ScheduledTrigger-3880-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1058005 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 1058193 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Logging to org.apache.logging.slf4j.Log4jLogger@6221f7d5 via org.mortbay.log.Slf4jLog [junit4] 2> 1058228 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1058681 DEBUG (ScheduledTrigger-3880-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1058712 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1058824 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_58221_hdfs____vzdm3c/webapp [junit4] 2> 1059346 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1059354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1059681 DEBUG (ScheduledTrigger-3880-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1060032 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58221 [junit4] 2> 1060681 DEBUG (ScheduledTrigger-3880-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1061354 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1061355 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1061709 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1062709 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1063427 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1063428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1063709 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1064041 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1064064 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1064073 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_59776_datanode____.om66ef/webapp [junit4] 2> 1064711 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1065428 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1065429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1065776 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1066079 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59776 [junit4] 2> 1066785 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1067429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1067429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1067429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1067429 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1067430 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1067430 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1067430 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1067808 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1067834 WARN (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1067835 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1067873 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_33435_datanode____ft8owt/webapp [junit4] 2> 1068882 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1069316 INFO (SUITE-MoveReplicaHDFSTest-seed#[29469F98CBD33C0C]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33435 [junit4] 2> 1069663 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1069672 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1069882 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1070883 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1071687 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1071688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1071766 ERROR (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:46078) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000 [junit4] 2> 1071766 ERROR (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_29469F98CBD33C0C-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:46078) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000 [junit4] 2> 1071890 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1072313 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x74ab2c21ad7ec9: from storage DS-1ecf5442-7f42-4869-9658-6c55d1d4e961 node DatanodeRegistration(127.0.0.1:37084, datanodeUuid=b818209b-2852-4929-b750-1db90a3f9628, infoPort=47245, infoSecurePort=0, ipcPort=36717, storageInfo=lv=-56;cid=testClusterID;nsid=1474938891;c=0), blocks: 0, hasStaleStorage: true, processing time: 2 msecs [junit4] 2> 1072313 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x74ab2c21ad7ec9: from storage DS-61987456-6d34-4166-8969-89ee3a4b9bff node DatanodeRegistration(127.0.0.1:37084, datanodeUuid=b818209b-2852-4929-b750-1db90a3f9628, infoPort=47245, infoSecurePort=0, ipcPort=36717, storageInfo=lv=-56;cid=testClusterID;nsid=1474938891;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 1072316 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x74ab2c21fcfc47: from storage DS-c60d7ae1-8cf8-4647-9fe5-2d09c28dba41 node DatanodeRegistration(127.0.0.1:39670, datanodeUuid=cd807042-a186-4324-945c-a1eda4969e2d, infoPort=58354, infoSecurePort=0, ipcPort=43045, storageInfo=lv=-56;cid=testClusterID;nsid=1474938891;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 1072316 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x74ab2c21fcfc47: from storage DS-031fb06a-f713-43e7-a281-7b78c6321984 node DatanodeRegistration(127.0.0.1:39670, datanodeUuid=cd807042-a186-4324-945c-a1eda4969e2d, infoPort=58354, infoSecurePort=0, ipcPort=43045, storageInfo=lv=-56;cid=testClusterID;nsid=1474938891;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 1072891 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1073688 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 1073689 DEBUG (OverseerCollectionConfigSetProcessor-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 1073891 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1074294 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFailedMove [junit4] 2> 1074295 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1074295 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1074295 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1074295 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1074296 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1074296 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074297 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 1074299 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] 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> 1074299 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074299 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074300 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074300 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074300 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074313 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074336 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1074336 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1074336 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1074337 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@70c256a9{/solr,null,AVAILABLE} [junit4] 2> 1074337 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074357 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074362 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074363 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@63302f1{SSL,[ssl, http/1.1]}{127.0.0.1:45647} [junit4] 2> 1074363 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.e.j.s.Server Started @1074434ms [junit4] 2> 1074363 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45647} [junit4] 2> 1074364 ERROR (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1074364 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1074364 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1074364 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1074364 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1074364 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-26T06:43:30.408Z [junit4] 2> 1074367 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 1074379 INFO (zkConnectionManagerCallback-2144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1074391 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1074891 DEBUG (ScheduledTrigger-3880-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 1074945 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38677/solr [junit4] 2> 1075071 INFO (zkConnectionManagerCallback-2148-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1075087 DEBUG (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$40/2049103816@7c151b88 [junit4] 2> 1075104 INFO (zkConnectionManagerCallback-2150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1075108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 1075108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 1075108 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1075108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 1075108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 1075108 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 1075109 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 1075110 DEBUG (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/74209749373288469-127.0.0.1:45647_solr-n_0000000004 [junit4] 2> 1075110 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/74209749373288462-127.0.0.1:47525_solr-n_0000000003 [junit4] 2> 1075110 DEBUG (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/74209749373288462-127.0.0.1:47525_solr-n_0000000003 to know if I could be the leader [junit4] 2> 1075110 DEBUG (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45647_solr as DOWN [junit4] 2> 1075111 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 1075111 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 1075111 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:45647_solr"} current state version: 0 [junit4] 2> 1075111 DEBUG (OverseerStateUpdate-74209749373288454-127.0.0.1:39449_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45647_solr [junit4] 2> 1075111 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1075111 INFO (TEST-MoveReplicaHDFSTest.testFailedMove-seed#[29469F98CBD33C0C]) [n:127.0.0.1:45647_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45647_solr [...truncated too long message...] .jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1637728902 [copy] Copying 240 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1637728902 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null1637728902 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 104ms :: artifacts dl 15ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 142 minutes 14 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$34.hasMatch(FilePath.java:2678) at hudson.FilePath$34.invoke(FilePath.java:2557) at hudson.FilePath$34.invoke(FilePath.java:2547) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1036) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) 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:1819) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1038) at hudson.FilePath.act(FilePath.java:1025) at hudson.FilePath.validateAntFileMask(FilePath.java:2547) 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:1819) 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/**,**/hs_err_pid*". Configuration error? Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
