Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/2084/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed. FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove Error Message: No live SolrServers available to handle this request:[http://127.0.0.1:35449/solr/MoveReplicaHDFSTest_failed_coll_true, http://127.0.0.1:41763/solr/MoveReplicaHDFSTest_failed_coll_true] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:35449/solr/MoveReplicaHDFSTest_failed_coll_true, http://127.0.0.1:41763/solr/MoveReplicaHDFSTest_failed_coll_true] at __randomizedtesting.SeedInfo.seed([BFB4BFC46A06B6E7:15796C36DDD56337]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:462) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:994) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) 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:301) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:41763/solr/MoveReplicaHDFSTest_failed_coll_true: no servers hosting shard: shard2 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:413) ... 46 more FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove Error Message: No live SolrServers available to handle this request:[http://127.0.0.1:50537/solr/MoveReplicaHDFSTest_failed_coll_true, http://127.0.0.1:62256/solr/MoveReplicaHDFSTest_failed_coll_true] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:50537/solr/MoveReplicaHDFSTest_failed_coll_true, http://127.0.0.1:62256/solr/MoveReplicaHDFSTest_failed_coll_true] at __randomizedtesting.SeedInfo.seed([BFB4BFC46A06B6E7:15796C36DDD56337]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:462) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1107) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:994) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) 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:301) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:62256/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 13890 lines...] [junit4] Suite: org.apache.solr.cloud.MoveReplicaHDFSTest [junit4] 2> 2403646 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/init-core-data-001 [junit4] 2> 2403647 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9 [junit4] 2> 2403647 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2403648 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 2403649 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001 [junit4] 2> 2403649 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2403649 INFO (Thread-5735) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2403649 INFO (Thread-5735) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2403651 ERROR (Thread-5735) [ ] 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> 2403749 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37583 [junit4] 2> 2403751 INFO (zkConnectionManagerCallback-8377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2403756 INFO (jetty-launcher-8374-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> 2403756 INFO (jetty-launcher-8374-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> 2403756 INFO (jetty-launcher-8374-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> 2403757 INFO (jetty-launcher-8374-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> 2403758 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2403758 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2403758 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2403759 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2403759 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2403759 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2403759 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@642dce76{/solr,null,AVAILABLE} [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60beb857{/solr,null,AVAILABLE} [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7c0d67fd{HTTP/1.1,[http/1.1]}{127.0.0.1:61383} [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-1) [ ] o.e.j.s.Server Started @2405359ms [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61383} [junit4] 2> 2403760 ERROR (jetty-launcher-8374-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2403760 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-30T17:39:47.507Z [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48ebb396{HTTP/1.1,[http/1.1]}{127.0.0.1:50537} [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.e.j.s.Server Started @2405360ms [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50537} [junit4] 2> 2403761 ERROR (jetty-launcher-8374-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-30T17:39:47.507Z [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e88f506{/solr,null,AVAILABLE} [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5fac4264{HTTP/1.1,[http/1.1]}{127.0.0.1:38924} [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.e.j.s.Server Started @2405360ms [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38924} [junit4] 2> 2403761 ERROR (jetty-launcher-8374-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2403761 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2403762 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2403762 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2403762 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-30T17:39:47.508Z [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c575323{/solr,null,AVAILABLE} [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@134d45b3{HTTP/1.1,[http/1.1]}{127.0.0.1:34585} [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.e.j.s.Server Started @2405362ms [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34585} [junit4] 2> 2403763 ERROR (jetty-launcher-8374-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2403763 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-30T17:39:47.509Z [junit4] 2> 2403764 INFO (zkConnectionManagerCallback-8383-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2403765 INFO (zkConnectionManagerCallback-8379-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2403765 INFO (zkConnectionManagerCallback-8385-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2403765 INFO (zkConnectionManagerCallback-8381-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2403765 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2403765 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2403765 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2403767 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2404064 INFO (jetty-launcher-8374-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37583/solr [junit4] 2> 2404065 INFO (zkConnectionManagerCallback-8389-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404066 DEBUG (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/458497280@5b3de575 [junit4] 2> 2404067 INFO (zkConnectionManagerCallback-8391-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404220 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404220 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404220 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404221 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404221 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404221 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2404224 DEBUG (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72093056576520198-127.0.0.1:61383_solr-n_0000000000 [junit4] 2> 2404225 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61383_solr [junit4] 2> 2404225 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.Overseer Overseer (id=72093056576520198-127.0.0.1:61383_solr-n_0000000000) starting [junit4] 2> 2404243 INFO (zkConnectionManagerCallback-8398-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404244 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404244 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404244 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404244 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404245 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404245 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2404246 INFO (OverseerStateUpdate-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61383_solr [junit4] 2> 2404246 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 2404246 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61383_solr [junit4] 2> 2404247 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/autoscaling.json [junit4] 2> 2404247 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404247 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 2404247 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404247 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 2404248 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 2404248 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404248 INFO (zkCallback-8397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2404248 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2404248 INFO (OverseerStateUpdate-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2404248 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404249 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue-work [junit4] 2> 2404249 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2404264 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2404273 INFO (jetty-launcher-8374-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37583/solr [junit4] 2> 2404275 INFO (zkConnectionManagerCallback-8403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404275 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 2404275 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2404276 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2404276 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2404276 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 2404276 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 2404276 DEBUG (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/458497280@12fd9425 [junit4] 2> 2404276 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2404277 INFO (zkConnectionManagerCallback-8405-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404284 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404284 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61383.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404285 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404285 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2404285 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404285 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404285 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404286 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2404287 DEBUG (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72093056576520201-127.0.0.1:50537_solr-n_0000000001 [junit4] 2> 2404288 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72093056576520198-127.0.0.1:61383_solr-n_0000000000 [junit4] 2> 2404288 DEBUG (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72093056576520198-127.0.0.1:61383_solr-n_0000000000 to know if I could be the leader [junit4] 2> 2404288 DEBUG (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50537_solr as DOWN [junit4] 2> 2404289 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2404289 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2404289 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2404289 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50537_solr [junit4] 2> 2404289 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61383.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404290 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61383.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404290 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404290 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404290 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404290 INFO (jetty-launcher-8374-thread-1) [n:127.0.0.1:61383_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001/node1/. [junit4] 2> 2404290 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404290 INFO (zkCallback-8390-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2404291 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404291 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404291 INFO (zkCallback-8397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2404294 INFO (zkCallback-8404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2404304 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 2404305 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:61383_solr, 127.0.0.1:50537_solr] [junit4] 2> 2404305 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2404305 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 2404306 DEBUG (OverseerAutoScalingTriggerThread-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 2404306 DEBUG (ScheduledTrigger-11140-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 2404313 INFO (zkConnectionManagerCallback-8412-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404314 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404314 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404314 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2404314 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404314 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404315 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404315 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2404315 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2404327 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50537.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404333 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50537.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404333 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50537.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404333 INFO (jetty-launcher-8374-thread-2) [n:127.0.0.1:50537_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001/node2/. [junit4] 2> 2404391 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2404392 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2404552 INFO (jetty-launcher-8374-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37583/solr [junit4] 2> 2404554 INFO (zkConnectionManagerCallback-8417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404555 DEBUG (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/458497280@f4a9cc9 [junit4] 2> 2404556 INFO (zkConnectionManagerCallback-8419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404565 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404565 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404565 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2404566 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404566 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404566 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404567 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2404569 DEBUG (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72093056576520204-127.0.0.1:34585_solr-n_0000000002 [junit4] 2> 2404569 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72093056576520201-127.0.0.1:50537_solr-n_0000000001 [junit4] 2> 2404570 DEBUG (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72093056576520201-127.0.0.1:50537_solr-n_0000000001 to know if I could be the leader [junit4] 2> 2404570 DEBUG (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34585_solr as DOWN [junit4] 2> 2404571 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2404571 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2404572 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2404572 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34585_solr [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404573 INFO (zkCallback-8397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404573 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404573 INFO (zkCallback-8390-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2404574 INFO (zkCallback-8404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2404574 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404574 INFO (zkCallback-8411-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2404574 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404574 INFO (zkCallback-8418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2404593 INFO (zkConnectionManagerCallback-8426-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2404594 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2404594 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2404594 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2404595 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2404595 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2404595 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2404595 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2404596 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2404608 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34585.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404613 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34585.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404614 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34585.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2404614 INFO (jetty-launcher-8374-thread-4) [n:127.0.0.1:34585_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001/node4/. [junit4] 2> 2404673 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2404674 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2405307 DEBUG (ScheduledTrigger-11140-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3 [junit4] 2> 2405320 INFO (jetty-launcher-8374-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37583/solr [junit4] 2> 2405321 INFO (zkConnectionManagerCallback-8431-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2405323 DEBUG (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/458497280@4969e7e6 [junit4] 2> 2405324 INFO (zkConnectionManagerCallback-8433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2405336 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2405336 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405336 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2405336 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2405337 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2405337 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2405338 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2405339 DEBUG (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72093056576520207-127.0.0.1:38924_solr-n_0000000003 [junit4] 2> 2405340 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72093056576520204-127.0.0.1:34585_solr-n_0000000002 [junit4] 2> 2405340 DEBUG (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72093056576520204-127.0.0.1:34585_solr-n_0000000002 to know if I could be the leader [junit4] 2> 2405340 DEBUG (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38924_solr as DOWN [junit4] 2> 2405341 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2405341 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2405341 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38924_solr [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405342 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2405343 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405343 INFO (zkCallback-8397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405343 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405343 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405343 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405344 INFO (zkCallback-8418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405344 INFO (zkCallback-8390-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405344 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405344 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405344 INFO (zkCallback-8425-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405344 INFO (zkCallback-8411-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405344 INFO (zkCallback-8404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405344 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405345 INFO (zkCallback-8432-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2405380 INFO (zkConnectionManagerCallback-8440-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2405381 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2405382 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405382 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2405382 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2405383 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2405383 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2405383 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2405384 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2405394 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38924.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2405403 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38924.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2405404 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38924.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2405404 INFO (jetty-launcher-8374-thread-3) [n:127.0.0.1:38924_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001/node3/. [junit4] 2> 2405430 INFO (zkConnectionManagerCallback-8443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2405434 INFO (zkConnectionManagerCallback-8448-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2405435 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2405436 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2405436 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2405437 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2405437 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2405437 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2405438 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2405444 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2405445 INFO (qtp1026803748-29059) [n:127.0.0.1:38924_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2405445 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2405445 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2405446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 2405446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2405446 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2405446 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2405447 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2405447 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2405447 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 2405447 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 2405447 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running [junit4] 2> 2405447 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{"operation":"overseerstatus"} [junit4] 2> 2405448 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000 [junit4] 2> 2405448 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : overseerstatus , {"operation":"overseerstatus"} [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2405448 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[] [junit4] 2> 2405450 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2405451 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 2405451 DEBUG (zkCallback-8432-thread-1) [ ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000000 state SyncConnected latchEventType null [junit4] 2> 2405451 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as completed. [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2405451 DEBUG (OverseerThreadFactory-11148-thread-1-processing-n:127.0.0.1:61383_solr) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Message id:/overseer/collection-queue-work/qn-0000000000 complete, response:{leader=127.0.0.1:61383_solr,overseer_queue_size=0,overseer_work_queue_size=0,overseer_collection_queue_size=2,overseer_operations={am_i_leader={requests=5,errors=0,avgRequestsPerSecond=4.150791428703696,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.41060312983414265,medianRequestTime=0.445872,75thPcRequestTime=0.483252,95thPcRequestTime=0.54168,99thPcRequestTime=0.54168,999thPcRequestTime=0.54168},downnode={requests=3,errors=0,avgRequestsPerSecond=2.585099742902524,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.011019393983820482,medianRequestTime=0.005738,75thPcRequestTime=0.02171,95thPcRequestTime=0.02171,99thPcRequestTime=0.02171,999thPcRequestTime=0.02171}},collection_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=2.495479412010782,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.3346276395965422,medianRequestTime=0.322036,75thPcRequestTime=0.35959,95thPcRequestTime=0.35959,99thPcRequestTime=0.35959,999thPcRequestTime=0.35959},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.8321236301388373,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.256169,medianRequestTime=0.256169,75thPcRequestTime=0.256169,95thPcRequestTime=0.256169,99thPcRequestTime=0.256169,999thPcRequestTime=0.256169}},collection_queue={peektopn_wait2000={avgRequestsPerSecond=0.8516694610012654,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=1171.826615,medianRequestTime=1171.826615,75thPcRequestTime=1171.826615,95thPcRequestTime=1171.826615,99thPcRequestTime=1171.826615,999thPcRequestTime=1171.826615}}} [junit4] 2> 2405452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2405452 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2405452 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2405453 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2405453 INFO (qtp1026803748-29059) [n:127.0.0.1:38924_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8 [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 2405521 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 2405525 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2405528 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2405541 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_serv1.vm3_59285_hdfs____v08824/webapp [junit4] 2> 2406019 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@serv1-vm3:59285 [junit4] 2> 2406135 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2406136 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2406149 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_54417_datanode____.bvr67y/webapp [junit4] 2> 2406311 DEBUG (ScheduledTrigger-11140-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 2406609 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54417 [junit4] 2> 2406673 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2406674 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2406685 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_62401_datanode____.664bkw/webapp [junit4] 2> 2406781 ERROR (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:43348) [ ] 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> 2406793 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1ec24fa687386: from storage DS-7b502d54-e24e-458d-908c-9b2bff119872 node DatanodeRegistration(127.0.0.1:38790, datanodeUuid=779a105c-b6ec-4b05-ba4f-0e008b521af6, infoPort=45142, infoSecurePort=0, ipcPort=58833, storageInfo=lv=-56;cid=testClusterID;nsid=944792505;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 2406793 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1ec24fa687386: from storage DS-e7a049d3-650c-43c1-9375-bed3e526c172 node DatanodeRegistration(127.0.0.1:38790, datanodeUuid=779a105c-b6ec-4b05-ba4f-0e008b521af6, infoPort=45142, infoSecurePort=0, ipcPort=58833, storageInfo=lv=-56;cid=testClusterID;nsid=944792505;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 2407220 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:62401 [junit4] 2> 2407314 DEBUG (ScheduledTrigger-11140-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 2407355 ERROR (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:43348) [ ] 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> 2407361 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1ec251c4ea201: from storage DS-b666a8e6-e6c4-4e30-b3ec-2bc5cdcb46a5 node DatanodeRegistration(127.0.0.1:48405, datanodeUuid=00b38543-c2d9-4c6b-97d1-679d97f0baed, infoPort=46561, infoSecurePort=0, ipcPort=41669, storageInfo=lv=-56;cid=testClusterID;nsid=944792505;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 2407361 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1ec251c4ea201: from storage DS-d07af340-66f7-4695-9b0f-86c7a8b60aa5 node DatanodeRegistration(127.0.0.1:48405, datanodeUuid=00b38543-c2d9-4c6b-97d1-679d97f0baed, infoPort=46561, infoSecurePort=0, ipcPort=41669, storageInfo=lv=-56;cid=testClusterID;nsid=944792505;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 2407452 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 2407452 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 2407453 INFO (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 2407454 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2407454 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2407454 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2407454 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 2407454 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 2407551 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNormalMove [junit4] 2> 2407552 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2407553 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407553 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2407553 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2407553 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2407554 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407555 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407559 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407559 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/aliases.json [junit4] 2> 2407560 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] 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> 2407561 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2407561 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2407561 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2407561 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@171b3b6{/solr,null,AVAILABLE} [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5e6a8c84{HTTP/1.1,[http/1.1]}{127.0.0.1:62256} [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.e.j.s.Server Started @2409163ms [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62256} [junit4] 2> 2407562 ERROR (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2407562 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-09-30T17:39:51.308Z [junit4] 2> 2407563 INFO (zkConnectionManagerCallback-8452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2407564 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2407868 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37583/solr [junit4] 2> 2407870 INFO (zkConnectionManagerCallback-8456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2407871 DEBUG (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/458497280@c5db30 [junit4] 2> 2407872 INFO (zkConnectionManagerCallback-8458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2407882 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2407883 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407883 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2407883 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2407884 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2407884 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2407885 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2407887 DEBUG (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72093056576520213-127.0.0.1:62256_solr-n_0000000004 [junit4] 2> 2407887 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72093056576520207-127.0.0.1:38924_solr-n_0000000003 [junit4] 2> 2407887 DEBUG (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72093056576520207-127.0.0.1:38924_solr-n_0000000003 to know if I could be the leader [junit4] 2> 2407888 DEBUG (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62256_solr as DOWN [junit4] 2> 2407889 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2407889 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2407889 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2407889 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62256_solr [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407891 INFO (zkCallback-8447-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407891 INFO (zkCallback-8390-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407891 INFO (zkCallback-8425-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407891 INFO (zkCallback-8432-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 INFO (zkCallback-8397-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407892 INFO (zkCallback-8404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 INFO (zkCallback-8411-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 INFO (zkCallback-8418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407892 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407892 INFO (zkCallback-8439-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 INFO (zkCallback-8447-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407892 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407892 INFO (zkCallback-8457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2407912 INFO (zkConnectionManagerCallback-8465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2407914 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 2407914 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2407914 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 2407915 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2407915 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2407915 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2407916 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37583/solr ready [junit4] 2> 2407916 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2407931 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62256.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2407939 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62256.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2407939 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62256.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@603f2c96 [junit4] 2> 2407940 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [n:127.0.0.1:62256_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-001/node5/. [junit4] 2> 2407981 INFO (zkConnectionManagerCallback-8468-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2407983 INFO (TEST-MoveReplicaHDFSTest.testNormalMove-seed#[BFB4BFC46A06B6E7]) [ ] o.a.s.c.MoveReplicaTest total_jettys: 5 [junit4] 2> 2407984 INFO (qtp1660725373-29050) [n:127.0.0.1:34585_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&maxShardsPerNode=2&autoAddReplicas=false&name=MoveReplicaHDFSTest_coll_false&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2407986 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2407986 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000002 [junit4] 2> 2407987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2407987 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 2407987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2407987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2407988 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 2407988 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [n:127.0.0.1:61383_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000002, ] [junit4] 2> 2407988 DEBUG (OverseerCollectionConfigSetProcessor-72093056576520198-127.0.0.1:61383_solr-n_0000000000) [...truncated too long message...] ?] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [junit4] 2> 197337 INFO (jetty-closer-129-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 197338 ERROR (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 197339 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60228 60228 [junit4] 2> 197458 INFO (Thread-2106) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60228 60228 [junit4] 2> 197460 WARN (Thread-2106) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 33 /solr/collections/MoveReplicaHDFSTest_coll_false/terms/shard1 [junit4] 2> 22 /solr/collections/MoveReplicaHDFSTest_coll_true/terms/shard1 [junit4] 2> 19 /solr/configs/conf1 [junit4] 2> 17 /solr/aliases.json [junit4] 2> 17 /solr/clusterprops.json [junit4] 2> 10 /solr/collections/MoveReplicaHDFSTest_coll_true/terms/shard2 [junit4] 2> 9 /solr/collections/MoveReplicaHDFSTest_coll_false/terms/shard2 [junit4] 2> 8 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/terms/shard1 [junit4] 2> 8 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/terms/shard2 [junit4] 2> 7 /solr/collections/MoveReplicaHDFSTest_failed_coll_false/terms/shard2 [junit4] 2> 7 /solr/collections/MoveReplicaHDFSTest_failed_coll_false/terms/shard1 [junit4] 2> 6 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 17 /solr/clusterstate.json [junit4] 2> 15 /solr/collections/MoveReplicaHDFSTest_coll_false/state.json [junit4] 2> 10 /solr/aliases.json [junit4] 2> 4 /solr/collections/MoveReplicaHDFSTest_coll_true/state.json [junit4] 2> 4 /solr/overseer_elect/election/72093248828932102-127.0.0.1:58469_solr-n_0000000000 [junit4] 2> 4 /solr/collections/MoveReplicaHDFSTest_failed_coll_false/state.json [junit4] 2> 4 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/state.json [junit4] 2> 2 /solr/collections/MoveReplicaHDFSTest_coll_true/leader_elect/shard1/election/72093248828932105-core_node5-n_0000000000 [junit4] 2> 2 /solr/collections/MoveReplicaHDFSTest_coll_false/leader_elect/shard1/election/72093248828932111-core_node3-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 32 /solr/collections [junit4] 2> 29 /solr/live_nodes [junit4] 2> [junit4] 2> 197462 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 197469 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 197573 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:40960) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 197573 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:40960) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-998357028-127.0.0.1-1538332120373 (Datanode Uuid b48c248b-37c2-49aa-b5a6-e208104cdd4f) service to serv1-vm3/127.0.0.1:40960 [junit4] 2> 197579 WARN (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 197585 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 197690 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:40960) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 197690 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:40960) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-998357028-127.0.0.1-1538332120373 (Datanode Uuid fac0cde1-f322-4707-a8bb-a5ecb0d706ba) service to serv1-vm3/127.0.0.1:40960 [junit4] 2> 197709 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@serv1-vm3:0 [junit4] 2> 197831 INFO (SUITE-MoveReplicaHDFSTest-seed#[BFB4BFC46A06B6E7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60228 60228 [junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_BFB4BFC46A06B6E7-001 [junit4] 2> Sep 30, 2018 6:30:00 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 27 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene80, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@32495463), locale=ko-KR, timezone=CST [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=2,free=79991760,total=212860928 [junit4] 2> NOTE: All tests run in this JVM: [MoveReplicaHDFSTest, MoveReplicaHDFSTest] [junit4] Completed [4/5 (2!)] on J1 in 91.42s, 4 tests, 1 error <<< FAILURES! [junit4] [junit4] Suite: org.apache.solr.cloud.MoveReplicaHDFSTest [junit4] OK 23.8s J0 | MoveReplicaHDFSTest.testNormalMove [junit4] OK 24.9s J0 | MoveReplicaHDFSTest.test [junit4] OK 13.8s J0 | MoveReplicaHDFSTest.testFailedMove [junit4] OK 15.8s J0 | MoveReplicaHDFSTest.testNormalFailedMove [junit4] Completed [5/5 (2!)] on J0 in 92.43s, 4 tests [junit4] [junit4] [junit4] Tests with failures [seed: BFB4BFC46A06B6E7]: [junit4] - org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove [junit4] - org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove [junit4] [junit4] [junit4] JVM J0: 0.68 .. 292.77 = 292.10s [junit4] JVM J1: 0.68 .. 208.88 = 208.20s [junit4] Execution time total: 4 minutes 52 seconds [junit4] Tests summary: 5 suites, 20 tests, 2 errors BUILD FAILED /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1567: The following error occurred while executing this line: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 2 errors [seed: BFB4BFC46A06B6E7] Total time: 4 minutes 54 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 2/5 failed: org.apache.solr.cloud.MoveReplicaHDFSTest [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=964cc88cee7d62edf03a923e3217809d630af5d5, workspace=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris [WARNINGS] Computing warning deltas based on reference build #2083 Recording test results Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org