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: [email protected]
For additional commands, e-mail: [email protected]
