Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1498/
2 tests failed. FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([3A2FC374AAA74EC3:90E210861D749B13]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at org.apache.solr.cloud.MoveReplicaTest.testFailedMove(MoveReplicaTest.java:303) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalMove Error Message: Error from server at https://127.0.0.1:33155/solr: delete the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33155/solr: delete the collection time out:180s at __randomizedtesting.SeedInfo.seed([3A2FC374AAA74EC3:9CF774D32F468CD9]:0) 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) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:451) at org.apache.solr.cloud.MoveReplicaTest.beforeTest(MoveReplicaTest.java:97) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 14547 lines...] [junit4] Suite: org.apache.solr.cloud.MoveReplicaHDFSTest [junit4] 2> 2500756 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/init-core-data-001 [junit4] 2> 2500757 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=256 numCloses=256 [junit4] 2> 2500758 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2500759 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 2500761 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001 [junit4] 2> 2500761 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2500763 INFO (Thread-14031) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2500765 INFO (Thread-14031) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2500773 ERROR (Thread-14031) [ ] 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> 2500864 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38690 [junit4] 2> 2500874 INFO (zkConnectionManagerCallback-3868-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500885 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2500887 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2500888 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2500889 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2500889 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2500889 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2500889 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2500890 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b00d9cb{/solr,null,AVAILABLE} [junit4] 2> 2500893 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17bbb107{SSL,[ssl, http/1.1]}{127.0.0.1:38989} [junit4] 2> 2500893 INFO (jetty-launcher-3865-thread-1) [ ] o.e.j.s.Server Started @2503986ms [junit4] 2> 2500893 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38989} [junit4] 2> 2500893 ERROR (jetty-launcher-3865-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2500894 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2500894 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2500894 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2500894 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-09T08:52:57.308Z [junit4] 2> 2500895 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2500895 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2500895 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2500896 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2500896 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2500896 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 2500896 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@475a367d{/solr,null,AVAILABLE} [junit4] 2> 2500896 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b778467{SSL,[ssl, http/1.1]}{127.0.0.1:33155} [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.e.j.s.Server Started @2503989ms [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33155} [junit4] 2> 2500897 ERROR (jetty-launcher-3865-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2500897 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-09T08:52:57.311Z [junit4] 2> 2500898 INFO (zkConnectionManagerCallback-3870-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500898 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44e8108c{/solr,null,AVAILABLE} [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ae7366e{SSL,[ssl, http/1.1]}{127.0.0.1:60838} [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-3) [ ] o.e.j.s.Server Started @2503992ms [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60838} [junit4] 2> 2500899 ERROR (jetty-launcher-3865-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2500899 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2500900 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2500900 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-09T08:52:57.314Z [junit4] 2> 2500901 INFO (zkConnectionManagerCallback-3872-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500901 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2500901 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2500901 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2500901 INFO (zkConnectionManagerCallback-3874-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4955d81d{/solr,null,AVAILABLE} [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@40ebf93b{SSL,[ssl, http/1.1]}{127.0.0.1:50839} [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.e.j.s.Server Started @2503995ms [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50839} [junit4] 2> 2500902 ERROR (jetty-launcher-3865-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2500902 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2500903 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2500903 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-09T08:52:57.317Z [junit4] 2> 2500905 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2500906 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2500906 INFO (zkConnectionManagerCallback-3876-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500911 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2500913 INFO (jetty-launcher-3865-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38690/solr [junit4] 2> 2500919 INFO (jetty-launcher-3865-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38690/solr [junit4] 2> 2500921 INFO (jetty-launcher-3865-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38690/solr [junit4] 2> 2500929 INFO (zkConnectionManagerCallback-3880-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500930 INFO (jetty-launcher-3865-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38690/solr [junit4] 2> 2500930 INFO (zkConnectionManagerCallback-3888-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500933 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x104da02b5630005, likely client has closed socket [junit4] 2> 2500933 INFO (zkConnectionManagerCallback-3892-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500933 DEBUG (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$283/552291203@5f089314 [junit4] 2> 2500933 INFO (zkConnectionManagerCallback-3884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500936 DEBUG (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$283/552291203@576ddd35 [junit4] 2> 2500937 DEBUG (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$283/552291203@713e8f8b [junit4] 2> 2500936 INFO (zkConnectionManagerCallback-3894-thread-1-processing-n:127.0.0.1:38989_solr) [n:127.0.0.1:38989_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500938 DEBUG (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$283/552291203@68db1aec [junit4] 2> 2500939 INFO (zkConnectionManagerCallback-3896-thread-1-processing-n:127.0.0.1:60838_solr) [n:127.0.0.1:60838_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500939 INFO (zkConnectionManagerCallback-3898-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500940 INFO (zkConnectionManagerCallback-3900-thread-1-processing-n:127.0.0.1:33155_solr) [n:127.0.0.1:33155_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2500976 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2500976 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2500977 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2500977 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2500978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2500978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2500983 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2500983 DEBUG (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/73423199112724491-127.0.0.1:50839_solr-n_0000000000 [junit4] 2> 2500983 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50839_solr [junit4] 2> 2500984 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer Overseer (id=73423199112724491-127.0.0.1:50839_solr-n_0000000000) starting [junit4] 2> 2500995 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2500995 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2500996 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50839_solr [junit4] 2> 2500997 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2500997 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue-work [junit4] 2> 2501003 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 2501005 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2501005 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501005 INFO (zkCallback-3897-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2501006 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 2501006 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/autoscaling.json [junit4] 2> 2501006 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2501006 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2501006 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2501006 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 2501007 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 2501007 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2501008 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 2501011 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:50839_solr] [junit4] 2> 2501011 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 2501011 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 2501016 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 2501018 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 2501019 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2501019 DEBUG (ScheduledTrigger-9277-thread-1) [n:127.0.0.1:50839_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 2501019 DEBUG (OverseerAutoScalingTriggerThread-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 2501104 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2501104 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501104 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2501104 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2501105 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2501105 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2501106 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2501108 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2501108 DEBUG (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/73423199112724489-127.0.0.1:38989_solr-n_0000000001 [junit4] 2> 2501109 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/73423199112724491-127.0.0.1:50839_solr-n_0000000000 [junit4] 2> 2501109 DEBUG (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/73423199112724491-127.0.0.1:50839_solr-n_0000000000 to know if I could be the leader [junit4] 2> 2501110 DEBUG (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38989_solr as DOWN [junit4] 2> 2501111 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2501112 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38989_solr [junit4] 2> 2501112 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2501112 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:38989_solr"} current state version: 0 [junit4] 2> 2501112 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38989_solr [junit4] 2> 2501114 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501114 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501114 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501115 INFO (zkCallback-3897-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2501116 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2501116 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501116 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2501116 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2501117 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2501117 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2501118 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2501119 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2501119 DEBUG (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/73423199112724490-127.0.0.1:60838_solr-n_0000000002 [junit4] 2> 2501120 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/73423199112724489-127.0.0.1:38989_solr-n_0000000001 [junit4] 2> 2501120 DEBUG (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/73423199112724489-127.0.0.1:38989_solr-n_0000000001 to know if I could be the leader [junit4] 2> 2501120 DEBUG (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60838_solr as DOWN [junit4] 2> 2501121 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2501122 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60838_solr [junit4] 2> 2501122 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2501122 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:60838_solr"} current state version: 0 [junit4] 2> 2501122 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501122 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:60838_solr [junit4] 2> 2501123 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501123 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501129 INFO (zkCallback-3897-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2501129 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501130 INFO (zkCallback-3893-thread-1-processing-n:127.0.0.1:38989_solr) [n:127.0.0.1:38989_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 2501130 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501130 INFO (zkCallback-3895-thread-1-processing-n:127.0.0.1:60838_solr) [n:127.0.0.1:60838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 2501131 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2501132 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501132 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 2501132 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2501132 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2501133 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2501133 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2501134 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2501135 DEBUG (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/73423199112724492-127.0.0.1:33155_solr-n_0000000003 [junit4] 2> 2501135 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/73423199112724490-127.0.0.1:60838_solr-n_0000000002 [junit4] 2> 2501135 DEBUG (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/73423199112724490-127.0.0.1:60838_solr-n_0000000002 to know if I could be the leader [junit4] 2> 2501135 DEBUG (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33155_solr as DOWN [junit4] 2> 2501136 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2501137 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33155_solr [junit4] 2> 2501137 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2501137 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:33155_solr"} current state version: 0 [junit4] 2> 2501137 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:33155_solr [junit4] 2> 2501137 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501137 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501138 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501138 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2501138 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501138 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501138 INFO (zkCallback-3895-thread-1-processing-n:127.0.0.1:60838_solr) [n:127.0.0.1:60838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2501140 INFO (zkCallback-3897-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2501142 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501143 INFO (zkCallback-3893-thread-1-processing-n:127.0.0.1:38989_solr) [n:127.0.0.1:38989_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2501143 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501144 INFO (zkCallback-3899-thread-1-processing-n:127.0.0.1:33155_solr) [n:127.0.0.1:33155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 2501228 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38989.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501238 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2501239 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2501240 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60838.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501255 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50839.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501255 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33155.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501287 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38989.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501287 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38989.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501289 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60838.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501290 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60838.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501292 INFO (jetty-launcher-3865-thread-1) [n:127.0.0.1:38989_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001/node1/. [junit4] 2> 2501293 INFO (jetty-launcher-3865-thread-3) [n:127.0.0.1:60838_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001/node3/. [junit4] 2> 2501297 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50839.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501297 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50839.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501299 INFO (jetty-launcher-3865-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001/node2/. [junit4] 2> 2501301 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33155.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501301 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33155.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2501303 INFO (jetty-launcher-3865-thread-2) [n:127.0.0.1:33155_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001/node4/. [junit4] 2> 2501511 INFO (zkConnectionManagerCallback-3910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2501516 INFO (zkConnectionManagerCallback-3914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2501516 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2501517 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2501517 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2501517 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2501518 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2501518 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2501518 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38690/solr ready [junit4] 2> 2501556 INFO (qtp819545665-29656) [n:127.0.0.1:60838_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2501556 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2501557 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2501557 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 2501557 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2501557 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2501558 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2501558 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2501558 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_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> 2501559 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000 [junit4] 2> 2501559 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : overseerstatus , {"operation":"overseerstatus"} [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2501559 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2501560 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[] [junit4] 2> 2501562 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2501562 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 2501562 DEBUG (zkCallback-3895-thread-1-processing-n:127.0.0.1:60838_solr) [n:127.0.0.1:60838_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000000 state SyncConnected latchEventType null [junit4] 2> 2501563 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2501563 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as completed. [junit4] 2> 2501563 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2501563 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2501563 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2501563 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2501563 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2501563 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 2501564 DEBUG (OverseerThreadFactory-9279-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_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:50839_solr,overseer_queue_size=0,overseer_work_queue_size=0,overseer_collection_queue_size=2,overseer_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=5.306252400183781,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.3592053333333333,medianRequestTime=0.34719,75thPcRequestTime=0.43594,95thPcRequestTime=0.43594,99thPcRequestTime=0.43594,999thPcRequestTime=0.43594},downnode={requests=3,errors=0,avgRequestsPerSecond=6.697837060780126,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.6004856666666666,medianRequestTime=0.133451,75thPcRequestTime=1.609773,95thPcRequestTime=1.609773,99thPcRequestTime=1.609773,999thPcRequestTime=1.609773}},collection_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=5.312210003135798,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.356649,medianRequestTime=0.282434,75thPcRequestTime=0.57368,95thPcRequestTime=0.57368,99thPcRequestTime=0.57368,999thPcRequestTime=0.57368},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=1.773678065189242,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=3.964448,medianRequestTime=3.964448,75thPcRequestTime=3.964448,95thPcRequestTime=3.964448,99thPcRequestTime=3.964448,999thPcRequestTime=3.964448}},collection_queue={peektopn_wait2000={avgRequestsPerSecond=1.8065391724769393,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=551.689304,medianRequestTime=551.689304,75thPcRequestTime=551.689304,95thPcRequestTime=551.689304,99thPcRequestTime=551.689304,999thPcRequestTime=551.689304}}} [junit4] 2> 2501564 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 2501564 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2501564 INFO (qtp819545665-29656) [n:127.0.0.1:60838_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> 2501610 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 2501623 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2501626 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2501643 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_44995_hdfs____x3rl8f/webapp [junit4] 2> 2502021 DEBUG (ScheduledTrigger-9277-thread-3) [n:127.0.0.1:50839_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 2502032 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44995 [junit4] 2> 2502133 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2502134 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2502149 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_33553_datanode____xq6aoq/webapp [junit4] 2> 2502657 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33553 [junit4] 2> 2502764 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2502765 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2502787 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_57745_datanode____m7f4sd/webapp [junit4] 2> 2502847 ERROR (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:46295) [ ] 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> 2502868 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x4a079451544de0: from storage DS-c62ff0df-60eb-42b5-9124-af460782bf84 node DatanodeRegistration(127.0.0.1:52349, datanodeUuid=1be33c35-a854-4af9-8654-11eeeb3b88c3, infoPort=52370, infoSecurePort=0, ipcPort=36837, storageInfo=lv=-56;cid=testClusterID;nsid=763785840;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 2502868 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x4a079451544de0: from storage DS-907c305e-51cb-45a5-92e3-118b08c100fd node DatanodeRegistration(127.0.0.1:52349, datanodeUuid=1be33c35-a854-4af9-8654-11eeeb3b88c3, infoPort=52370, infoSecurePort=0, ipcPort=36837, storageInfo=lv=-56;cid=testClusterID;nsid=763785840;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 2503022 DEBUG (ScheduledTrigger-9277-thread-4) [n:127.0.0.1:50839_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 2503286 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57745 [junit4] 2> 2503509 ERROR (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:46295) [ ] 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> 2503517 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x4a0794781c0154: from storage DS-a65462db-a184-494b-9f98-67a85fb70df9 node DatanodeRegistration(127.0.0.1:53782, datanodeUuid=60f15929-a6fe-419b-9764-aee630156c21, infoPort=49680, infoSecurePort=0, ipcPort=37935, storageInfo=lv=-56;cid=testClusterID;nsid=763785840;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 2503518 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x4a0794781c0154: from storage DS-1106e61c-5254-4a32-b3d3-d6c12263ab92 node DatanodeRegistration(127.0.0.1:53782, datanodeUuid=60f15929-a6fe-419b-9764-aee630156c21, infoPort=49680, infoSecurePort=0, ipcPort=37935, storageInfo=lv=-56;cid=testClusterID;nsid=763785840;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 2503561 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 2503562 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 2503562 INFO (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_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> 2503562 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 2503562 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2503562 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2503562 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 2503563 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] IGNOR/A 0.00s J1 | MoveReplicaHDFSTest.testNormalFailedMove [junit4] > Assumption #1: 'badapple' test group is disabled (@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028)) [junit4] 2> 2503833 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 2503834 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2503834 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503835 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2503835 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2503835 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2503835 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2503837 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2503865 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2503865 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2503865 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2503868 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1351cd2d{/solr,null,AVAILABLE} [junit4] 2> 2503868 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@cb1b24e{SSL,[ssl, http/1.1]}{127.0.0.1:57722} [junit4] 2> 2503868 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.e.j.s.Server Started @2506961ms [junit4] 2> 2503869 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57722} [junit4] 2> 2503869 ERROR (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2503869 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2503869 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2503869 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2503869 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-09T08:53:00.283Z [junit4] 2> 2503871 INFO (zkConnectionManagerCallback-3917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2503872 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2503882 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38690/solr [junit4] 2> 2503884 INFO (zkConnectionManagerCallback-3921-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2503885 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$283/552291203@7a742842 [junit4] 2> 2503886 INFO (zkConnectionManagerCallback-3923-thread-1-processing-n:127.0.0.1:57722_solr) [n:127.0.0.1:57722_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2503892 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterprops.json [junit4] 2> 2503893 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503893 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 2503893 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 2503893 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2503893 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 2503894 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 2503895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2503895 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/73423199112724497-127.0.0.1:57722_solr-n_0000000004 [junit4] 2> 2503896 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/73423199112724492-127.0.0.1:33155_solr-n_0000000003 [junit4] 2> 2503896 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/73423199112724492-127.0.0.1:33155_solr-n_0000000003 to know if I could be the leader [junit4] 2> 2503896 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57722_solr as DOWN [junit4] 2> 2503897 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2503898 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57722_solr [junit4] 2> 2503898 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2503898 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:57722_solr"} current state version: 0 [junit4] 2> 2503898 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503898 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:57722_solr [junit4] 2> 2503898 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503899 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503899 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503899 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503899 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 2503900 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503900 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503900 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503900 INFO (zkCallback-3893-thread-1-processing-n:127.0.0.1:38989_solr) [n:127.0.0.1:38989_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503900 INFO (zkCallback-3895-thread-1-processing-n:127.0.0.1:60838_solr) [n:127.0.0.1:60838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503900 INFO (zkCallback-3897-thread-1-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503901 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503901 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503901 INFO (zkCallback-3899-thread-1-processing-n:127.0.0.1:33155_solr) [n:127.0.0.1:33155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503901 INFO (zkCallback-3913-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503902 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503902 INFO (zkCallback-3913-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503909 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 2503909 INFO (zkCallback-3922-thread-1-processing-n:127.0.0.1:57722_solr) [n:127.0.0.1:57722_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 2503999 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2504000 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2504022 DEBUG (ScheduledTrigger-9277-thread-3) [n:127.0.0.1:50839_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 5 [junit4] 2> 2504071 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57722.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2504087 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57722.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2504087 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57722.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f995404 [junit4] 2> 2504089 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [n:127.0.0.1:57722_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-001/node5/. [junit4] 2> 2504177 INFO (zkConnectionManagerCallback-3927-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2504179 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x104da02b5630012, likely client has closed socket [junit4] 2> 2504179 INFO (TEST-MoveReplicaHDFSTest.test-seed#[3A2FC374AAA74EC3]) [ ] o.a.s.c.MoveReplicaTest total_jettys: 5 [junit4] 2> 2504213 INFO (qtp1558530514-29649) [n:127.0.0.1:33155_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf1&maxShardsPerNode=2&autoAddReplicas=false&name=MoveReplicaHDFSTest_coll_true&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2504217 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2504217 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000002 [junit4] 2> 2504217 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2504217 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 2504218 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 2504218 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 2504218 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 2504218 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000002, ] [junit4] 2> 2504219 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ed1]] [junit4] 2> 2504219 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000002] as running [junit4] 2> 2504219 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000002 message:{ [junit4] 2> "name":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} [junit4] 2> 2504219 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000002 [junit4] 2> 2504219 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , { [junit4] 2> "name":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} [junit4] 2> 2504219 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 2504220 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000002] [junit4] 2> 2504220 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 2504220 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 2504220 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000002] [junit4] 2> 2504219 INFO (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection MoveReplicaHDFSTest_coll_true [junit4] 2> 2504220 DEBUG (OverseerCollectionConfigSetProcessor-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[] [junit4] 2> 2504221 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.Assign Identify nodes using default [junit4] 2> 2504221 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/MoveReplicaHDFSTest_coll_true [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504223 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504224 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 2504225 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode:MoveReplicaHDFSTest_coll_true [junit4] 2> 2504226 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists [junit4] 2> 2504228 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2504229 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2504229 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "name":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} current state version: 0 [junit4] 2> 2504229 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: MoveReplicaHDFSTest_coll_true [junit4] 2> 2504230 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/MoveReplicaHDFSTest_coll_true/state.json [junit4] 2> 2504233 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2504234 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2504329 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores for new collection MoveReplicaHDFSTest_coll_true, shardNames [shard1, shard2] , message : { [junit4] 2> "name":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "fromApi":"true", [junit4] 2> "replicationFactor":"2", [junit4] 2> "collection.configName":"conf1", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "stateFormat":"2", [junit4] 2> "operation":"create"} [junit4] 2> 2504331 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core MoveReplicaHDFSTest_coll_true_shard1_replica_n1 as part of shard shard1 of collection MoveReplicaHDFSTest_coll_true on 127.0.0.1:57722_solr [junit4] 2> 2504332 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2504332 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2504333 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"MoveReplicaHDFSTest_coll_true_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:57722/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 2504333 INFO (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"MoveReplicaHDFSTest_coll_true_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:57722/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2504334 DEBUG (OverseerThreadFactory-9279-thread-2-processing-n:127.0.0.1:50839_solr) [n:127.0.0.1:50839_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core MoveReplicaHDFSTest_coll_true_shard1_replica_n2 as part of shard shard1 of collection MoveReplicaHDFSTest_coll_true on 127.0.0.1:50839_solr [junit4] 2> 2504335 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 2504335 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node3":{ [junit4] 2> "core":"MoveReplicaHDFSTest_coll_true_shard1_replica_n1", [junit4] 2> "base_url":"https://127.0.0.1:57722/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 2504335 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 2504336 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 2504336 DEBUG (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"MoveReplicaHDFSTest_coll_true", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"MoveReplicaHDFSTest_coll_true_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:50839/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 2504336 INFO (OverseerStateUpdate-73423199112724491-127.0.0.1:50839_solr-n_0000000000) [n:127.0.0.1:50839_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operati [...truncated too long message...] [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 3 /solr/overseer_elect/election/73423199112724491-127.0.0.1:50839_solr-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/73423199112724489-127.0.0.1:38989_solr-n_0000000001 [junit4] 2> 2 /solr/collections/MoveReplicaHDFSTest_coll_true/leader_elect/shard2/election/73423199112724489-core_node7-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 14 /solr/collections [junit4] 2> 12 /solr/live_nodes [junit4] 2> [junit4] 2> 2829050 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 2829081 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 2829183 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:46295) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 2829184 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:46295) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1368281937-10.41.0.5-1520585577994 (Datanode Uuid 60f15929-a6fe-419b-9764-aee630156c21) service to localhost/127.0.0.1:46295 [junit4] 2> 2829191 WARN (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 2829227 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 2829228 WARN (1205219577@qtp-1089428801-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33553) [ ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false. Rechecking. [junit4] 2> 2829228 WARN (1205219577@qtp-1089428801-1 - Acceptor0 HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33553) [ ] o.a.h.h.HttpServer2 HttpServer Acceptor: isRunning is false [junit4] 2> 2829232 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:46295) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 2829233 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:46295) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1368281937-10.41.0.5-1520585577994 (Datanode Uuid 1be33c35-a854-4af9-8654-11eeeb3b88c3) service to localhost/127.0.0.1:46295 [junit4] 2> 2829249 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 2829383 INFO (SUITE-MoveReplicaHDFSTest-seed#[3A2FC374AAA74EC3]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38690 38690 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.MoveReplicaHDFSTest_3A2FC374AAA74EC3-001 [junit4] 2> Mar 09, 2018 8:58:26 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 34 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1d7a7ad4), locale=lt-LT, timezone=Africa/Mbabane [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=2,free=237261352,total=507510784 [junit4] 2> NOTE: All tests run in this JVM: [DistributedMLTComponentTest, TestConfigOverlay, LargeFieldTest, LeaderFailoverAfterPartitionTest, TestRawTransformer, SortSpecParsingTest, TestDistributedStatsComponentCardinality, DocExpirationUpdateProcessorFactoryTest, TestFieldSortValues, StatsReloadRaceTest, TestSweetSpotSimilarityFactory, SyncSliceTest, PrimUtilsTest, TestStressRecovery, AutoscalingHistoryHandlerTest, SolrJmxReporterTest, TestComplexPhraseLeadingWildcard, TestRecovery, TestLeaderElectionWithEmptyReplica, JsonLoaderTest, TestSolr4Spatial, TestCharFilters, SuggesterFSTTest, MetricUtilsTest, TestFieldCache, TestSchemaVersionResource, HdfsDirectoryFactoryTest, ForceLeaderTest, IndexSchemaTest, BitVectorTest, DistributedFacetPivotSmallTest, JvmMetricsTest, VMParamsZkACLAndCredentialsProvidersTest, MultiThreadedOCPTest, SpatialHeatmapFacetsTest, PeerSyncReplicationTest, SoftAutoCommitTest, TestSolrCoreSnapshots, BlockCacheTest, RuleEngineTest, TestTriggerIntegration, ResourceLoaderTest, SolrCoreTest, DistributedQueueTest, TestCollationFieldDocValues, TestReversedWildcardFilterFactory, CloudExitableDirectoryReaderTest, HttpSolrCallGetCoreTest, DistributedIntervalFacetingTest, UpdateLogTest, TestTrieFacet, CollectionsAPISolrJTest, TestCryptoKeys, TestImpersonationWithHadoopAuth, LoggingHandlerTest, PropertiesRequestHandlerTest, AutoScalingHandlerTest, TestMaxScoreQueryParser, HdfsRecoveryZkTest, TestPolicyCloud, RestartWhileUpdatingTest, TestUtilizeNode, QueryEqualityTest, TestElisionMultitermQuery, TestJavabinTupleStreamParser, TestCoreBackup, TestStressVersions, TestLRUCache, TestHashQParserPlugin, TestPerFieldSimilarityWithDefaultOverride, ConnectionManagerTest, ReplaceNodeNoTargetTest, EnumFieldTest, AlternateDirectoryTest, TestSchemaManager, TestSQLHandlerNonCloud, TestSolrJ, SliceStateTest, TestCloudConsistency, TestReplicationHandler, TestReloadDeadlock, TestSolrFieldCacheBean, TestUninvertingReader, SchemaVersionSpecificBehaviorTest, SchemaApiFailureTest, CdcrUpdateLogTest, CustomCollectionTest, TestSimpleTrackingShardHandler, TestStressCloudBlindAtomicUpdates, TestUseDocValuesAsStored, SimplePostToolTest, CurrencyFieldTypeTest, TestMacroExpander, BJQParserTest, UninvertDocValuesMergePolicyTest, HdfsThreadLeakTest, SpellCheckCollatorWithCollapseTest, TestCursorMarkWithoutUniqueKey, PeerSyncTest, TestComputePlanAction, TestNumericRangeQuery64, TestExportWriter, TestZkChroot, SolrGangliaReporterTest, TestDistributedGrouping, TestCloudRecovery, CdcrRequestHandlerTest, TestBM25SimilarityFactory, TestSystemCollAutoCreate, HdfsAutoAddReplicasIntegrationTest, TestHdfsUpdateLog, TestSolrConfigHandler, HighlighterMaxOffsetTest, DistributedQueryComponentOptimizationTest, TestLegacyTerms, TestQueryUtils, BadCopyFieldTest, TestLegacyFieldCache, TestConfigsApi, SimpleCollectionCreateDeleteTest, AnalyticsQueryTest, CdcrBootstrapTest, PKIAuthenticationIntegrationTest, TestPerFieldSimilarity, TestJsonRequest, TestEmbeddedSolrServerSchemaAPI, TestManagedStopFilterFactory, DistribCursorPagingTest, BasicDistributedZkTest, OverseerTest, ShardRoutingTest, BasicZkTest, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestReload, TestCoreContainer, SpellCheckComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, TestSort, TestLazyCores, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTSTTest, SuggesterTest, SpatialFilterTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, StatelessScriptUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestCollationField, QueryParsingTest, CSVRequestHandlerTest, SOLR749Test, TestSolrIndexConfig, ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, MinimalSchemaTest, OutputWriterTest, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, SpellPossibilityIteratorTest, EchoParamsTest, ScriptEngineTest, DateMathParserTest, DateFieldTest, UUIDFieldTest, TestLuceneIndexBackCompat, ActionThrottleTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, MoveReplicaHDFSTest] [junit4] Completed [574/783 (1!)] on J1 in 338.49s, 4 tests, 1 failure, 1 error, 1 skipped <<< FAILURES! [...truncated 50144 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org