Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1715/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testNormalMove Error Message: Error from server at http://127.0.0.1:47815/solr: delete the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47815/solr: delete the collection time out:180s at __randomizedtesting.SeedInfo.seed([39BF131140344BF0:9F67A4B6C5D589EA]: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) FAILED: org.apache.solr.cloud.MoveReplicaHDFSTest.testFailedMove Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([39BF131140344BF0:9372C0E3F7E79E20]: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) Build Log: [...truncated 12597 lines...] [junit4] Suite: org.apache.solr.cloud.MoveReplicaHDFSTest [junit4] 2> 701219 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/init-core-data-001 [junit4] 2> 701221 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 701221 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 701223 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 701224 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001 [junit4] 2> 701224 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 701224 INFO (Thread-1375) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 701224 INFO (Thread-1375) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 701227 ERROR (Thread-1375) [ ] 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> 701328 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55788 [junit4] 2> 701343 INFO (zkConnectionManagerCallback-2780-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701416 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 701417 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 701417 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 701417 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 701417 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f9d8edb{/solr,null,AVAILABLE} [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@567e72c{HTTP/1.1,[http/1.1]}{127.0.0.1:61572} [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.e.j.s.Server Started @703436ms [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61572} [junit4] 2> 701418 ERROR (jetty-launcher-2777-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 701418 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-07T03:33:15.615Z [junit4] 2> 701420 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 701421 INFO (zkConnectionManagerCallback-2782-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701421 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 701423 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 701423 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 701423 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 701423 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6f5c7160{/solr,null,AVAILABLE} [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1616d058{HTTP/1.1,[http/1.1]}{127.0.0.1:48098} [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.e.j.s.Server Started @703442ms [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=48098} [junit4] 2> 701424 ERROR (jetty-launcher-2777-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 701424 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-07T03:33:15.621Z [junit4] 2> 701427 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 701428 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 701428 INFO (zkConnectionManagerCallback-2784-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701429 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 701437 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 701437 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 701438 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 701438 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@651fb43e{/solr,null,AVAILABLE} [junit4] 2> 701438 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@23768052{HTTP/1.1,[http/1.1]}{127.0.0.1:53026} [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.e.j.s.Server Started @703457ms [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53026} [junit4] 2> 701439 ERROR (jetty-launcher-2777-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 701439 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-07T03:33:15.636Z [junit4] 2> 701445 INFO (jetty-launcher-2777-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55788/solr [junit4] 2> 701448 INFO (zkConnectionManagerCallback-2790-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701450 DEBUG (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$297/1814522067@72e64291 [junit4] 2> 701454 INFO (zkConnectionManagerCallback-2786-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701457 INFO (zkConnectionManagerCallback-2792-thread-1-processing-n:127.0.0.1:48098_solr) [n:127.0.0.1:48098_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701448 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 701457 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 701457 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 701457 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4640a1a5{/solr,null,AVAILABLE} [junit4] 2> 701458 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 701459 INFO (jetty-launcher-2777-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55788/solr [junit4] 2> 701459 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@519f96a6{HTTP/1.1,[http/1.1]}{127.0.0.1:65153} [junit4] 2> 701461 INFO (jetty-launcher-2777-thread-3) [ ] o.e.j.s.Server Started @703480ms [junit4] 2> 701461 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65153} [junit4] 2> 701462 ERROR (jetty-launcher-2777-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 701462 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 701462 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 701462 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 701462 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-07T03:33:15.659Z [junit4] 2> 701466 INFO (zkConnectionManagerCallback-2796-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701466 INFO (zkConnectionManagerCallback-2798-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701467 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 701471 INFO (jetty-launcher-2777-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55788/solr [junit4] 2> 701484 DEBUG (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$297/1814522067@1f71b5a0 [junit4] 2> 701487 INFO (zkConnectionManagerCallback-2802-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701488 INFO (zkConnectionManagerCallback-2804-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701491 DEBUG (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$297/1814522067@4e67e2e9 [junit4] 2> 701492 INFO (jetty-launcher-2777-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55788/solr [junit4] 2> 701493 INFO (zkConnectionManagerCallback-2806-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701494 INFO (zkConnectionManagerCallback-2810-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701519 DEBUG (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$297/1814522067@7bc4c92e [junit4] 2> 701532 INFO (zkConnectionManagerCallback-2812-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 701619 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 701619 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 701620 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701620 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701621 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 701622 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 701622 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 701623 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 701623 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 701624 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 701627 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 701627 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 701644 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 701644 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 701646 DEBUG (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72092382110220297-127.0.0.1:61572_solr-n_0000000000 [junit4] 2> 701646 DEBUG (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72092382110220300-127.0.0.1:65153_solr-n_0000000001 [junit4] 2> 701649 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61572_solr [junit4] 2> 701652 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72092382110220297-127.0.0.1:61572_solr-n_0000000000 [junit4] 2> 701653 DEBUG (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72092382110220297-127.0.0.1:61572_solr-n_0000000000 to know if I could be the leader [junit4] 2> 701653 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65153_solr [junit4] 2> 701654 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer Overseer (id=72092382110220297-127.0.0.1:61572_solr-n_0000000000) starting [junit4] 2> 701654 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 701660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 701662 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701662 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701663 INFO (zkCallback-2811-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 701711 INFO (zkCallback-2803-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 701844 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 701845 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701845 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 701845 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 701846 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 701846 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 701847 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 701850 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 701850 DEBUG (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72092382110220298-127.0.0.1:53026_solr-n_0000000002 [junit4] 2> 701851 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72092382110220300-127.0.0.1:65153_solr-n_0000000001 [junit4] 2> 701851 DEBUG (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72092382110220300-127.0.0.1:65153_solr-n_0000000001 to know if I could be the leader [junit4] 2> 701852 DEBUG (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:53026_solr as DOWN [junit4] 2> 701857 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53026_solr [junit4] 2> 701883 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65153.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 701891 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65153.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 701891 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65153.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 701893 INFO (jetty-launcher-2777-thread-3) [n:127.0.0.1:65153_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001/node2/. [junit4] 2> 701915 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 701916 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 701916 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 701918 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701918 INFO (zkCallback-2811-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 701943 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701951 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 701952 INFO (zkCallback-2803-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 701962 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 701963 DEBUG (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61572_solr as DOWN [junit4] 2> 701964 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 701987 INFO (zkCallback-2805-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 701989 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 702019 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 702019 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 702019 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 702019 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 702019 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 702020 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 702020 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61572_solr [junit4] 2> 702020 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/autoscaling.json [junit4] 2> 702020 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 702020 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 702020 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue-work [junit4] 2> 702021 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:65153_solr, 127.0.0.1:53026_solr] [junit4] 2> 702021 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 702021 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 702021 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 702021 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702021 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702021 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702022 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702022 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702022 INFO (zkCallback-2805-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 702022 INFO (zkCallback-2811-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 702022 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702023 INFO (zkCallback-2803-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 702023 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:53026_solr"} current state version: 0 [junit4] 2> 702023 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:53026_solr [junit4] 2> 702023 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:61572_solr"} current state version: 0 [junit4] 2> 702023 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:61572_solr [junit4] 2> 702064 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 702065 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702065 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 702065 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 702065 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 702066 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 702066 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 702068 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 702069 DEBUG (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72092382110220293-127.0.0.1:48098_solr-n_0000000003 [junit4] 2> 702070 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72092382110220298-127.0.0.1:53026_solr-n_0000000002 [junit4] 2> 702070 DEBUG (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72092382110220298-127.0.0.1:53026_solr-n_0000000002 to know if I could be the leader [junit4] 2> 702070 DEBUG (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:48098_solr as DOWN [junit4] 2> 702072 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 702072 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48098_solr [junit4] 2> 702073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 702073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 702088 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702089 INFO (zkCallback-2805-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 702091 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702091 INFO (zkCallback-2811-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 702099 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:48098_solr"} current state version: 0 [junit4] 2> 702099 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:48098_solr [junit4] 2> 702099 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702100 INFO (zkCallback-2791-thread-1-processing-n:127.0.0.1:48098_solr) [n:127.0.0.1:48098_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 702145 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702146 INFO (zkCallback-2803-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 702157 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 702162 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 702163 DEBUG (OverseerAutoScalingTriggerThread-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 702213 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61572.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702221 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61572.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702221 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61572.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702222 INFO (jetty-launcher-2777-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001/node1/. [junit4] 2> 702229 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 702229 DEBUG (ScheduledTrigger-1804-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 702356 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 702368 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53026.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702390 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53026.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702390 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53026.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702393 INFO (jetty-launcher-2777-thread-4) [n:127.0.0.1:53026_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001/node4/. [junit4] 2> 702522 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48098.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702532 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48098.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702532 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48098.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 702533 INFO (jetty-launcher-2777-thread-2) [n:127.0.0.1:48098_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001/node3/. [junit4] 2> 702608 INFO (zkConnectionManagerCallback-2822-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 702611 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 0x1001fa3bab1000d, likely client has closed socket [junit4] 2> 702615 INFO (zkConnectionManagerCallback-2826-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 702618 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 702621 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 702621 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 702622 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 702622 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 702622 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 702624 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55788/solr ready [junit4] 2> 702641 INFO (qtp819588872-6734) [n:127.0.0.1:53026_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 702671 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 702671 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 702672 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 702672 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 702672 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 702673 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 702673 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 702673 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000000, ] [junit4] 2> 702673 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]] [junit4] 2> 702677 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running [junit4] 2> 702677 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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> 702678 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000 [junit4] 2> 702679 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : overseerstatus , {"operation":"overseerstatus"} [junit4] 2> 702679 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 702680 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 702680 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 702680 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 702680 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 702680 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[] [junit4] 2> 702696 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 702697 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: data: /solr/overseer/collection-queue-work/qnr-0000000000 [junit4] 2> 702697 DEBUG (zkCallback-2805-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path /overseer/collection-queue-work/qnr-0000000000 state SyncConnected latchEventType null [junit4] 2> 702697 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as completed. [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000] [junit4] 2> 702698 DEBUG (OverseerThreadFactory-1806-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_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:61572_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=4.09754489724334,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=8.607106333333332,medianRequestTime=1.330788,75thPcRequestTime=23.430254,95thPcRequestTime=23.430254,99thPcRequestTime=23.430254,999thPcRequestTime=23.430254},downnode={requests=3,errors=0,avgRequestsPerSecond=4.4681288769339895,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.03544766666666666,medianRequestTime=0.035737,75thPcRequestTime=0.044323,95thPcRequestTime=0.044323,99thPcRequestTime=0.044323,999thPcRequestTime=0.044323}},collection_operations={am_i_leader={requests=3,errors=0,avgRequestsPerSecond=4.099004462625782,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=11.125222,medianRequestTime=1.718103,75thPcRequestTime=30.293067,95thPcRequestTime=30.293067,99thPcRequestTime=30.293067,999thPcRequestTime=30.293067},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.4818311607058265,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=0.657394,medianRequestTime=0.657394,75thPcRequestTime=0.657394,95thPcRequestTime=0.657394,99thPcRequestTime=0.657394,999thPcRequestTime=0.657394}},collection_queue={peektopn_wait2000={avgRequestsPerSecond=1.4817166551587166,5minRateRequestsPerSecond=0.0,15minRateRequestsPerSecond=0.0,avgTimePerRequest=653.85839,medianRequestTime=653.85839,75thPcRequestTime=653.85839,95thPcRequestTime=653.85839,99thPcRequestTime=653.85839,999thPcRequestTime=653.85839}}} [junit4] 2> 702698 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 702698 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000000 [junit4] 2> 702699 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 702700 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 702700 INFO (qtp819588872-6734) [n:127.0.0.1:53026_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=59 [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 702824 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 702835 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 702842 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 702860 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_serv1.vm3_54614_hdfs____p4qzvf/webapp [junit4] 2> 703357 DEBUG (ScheduledTrigger-1804-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 703772 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@serv1-vm3:54614 [junit4] 2> 704073 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 704077 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 704110 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_59235_datanode____v2xqs7/webapp [junit4] 2> 704368 DEBUG (ScheduledTrigger-1804-thread-1) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 704639 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59235 [junit4] 2> 704681 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 704681 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 704694 INFO (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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> 704695 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 704695 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 704695 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 704695 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 704695 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 704960 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 704963 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 704996 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Extract jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_57791_datanode____.6to18/webapp [junit4] 2> 705129 ERROR (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] 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> 705236 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1e2c8fe66cc3b: from storage DS-7e72f2e7-f9a0-41ed-b05f-ec6ebf283f4a node DatanodeRegistration(127.0.0.1:58917, datanodeUuid=5e5814af-529c-4e93-8ccc-cd5dc51bd24b, infoPort=37182, infoSecurePort=0, ipcPort=34064, storageInfo=lv=-56;cid=testClusterID;nsid=1054523975;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 705236 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1e2c8fe66cc3b: from storage DS-b4f373e0-b14d-4dd4-bb5b-1472ecd54bc5 node DatanodeRegistration(127.0.0.1:58917, datanodeUuid=5e5814af-529c-4e93-8ccc-cd5dc51bd24b, infoPort=37182, infoSecurePort=0, ipcPort=34064, storageInfo=lv=-56;cid=testClusterID;nsid=1054523975;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 705780 DEBUG (ScheduledTrigger-1804-thread-3) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 706209 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57791 [junit4] 2> 706552 ERROR (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] 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> 706571 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1e2c94e097fd4: from storage DS-6e3e75a3-1521-424d-bca6-29ebb8ce54a7 node DatanodeRegistration(127.0.0.1:35779, datanodeUuid=52bb7583-dd19-4c64-9edc-642dc1629dad, infoPort=41444, infoSecurePort=0, ipcPort=63808, storageInfo=lv=-56;cid=testClusterID;nsid=1054523975;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 706571 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1e2c94e097fd4: from storage DS-b3d92780-1b45-4155-9555-4e77f693de4c node DatanodeRegistration(127.0.0.1:35779, datanodeUuid=52bb7583-dd19-4c64-9edc-642dc1629dad, infoPort=41444, infoSecurePort=0, ipcPort=63808, storageInfo=lv=-56;cid=testClusterID;nsid=1054523975;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 706702 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Got 0 tasks from work-queue : [[]] [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [] [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [] [junit4] 2> 706705 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: [] [junit4] 2> 706781 DEBUG (ScheduledTrigger-1804-thread-3) [n:127.0.0.1:61572_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 4 [junit4] 2> 706846 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 706887 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 706888 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706888 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 706889 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 706889 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 706890 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 706892 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 706894 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 706894 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 706894 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 706894 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@436ce8a4{/solr,null,AVAILABLE} [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5fc5cbc3{HTTP/1.1,[http/1.1]}{127.0.0.1:47815} [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.e.j.s.Server Started @708914ms [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=47815} [junit4] 2> 706895 ERROR (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 706895 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-07T03:33:21.092Z [junit4] 2> 706901 INFO (zkConnectionManagerCallback-2829-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 706902 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 706925 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55788/solr [junit4] 2> 706927 INFO (zkConnectionManagerCallback-2833-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 706929 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$297/1814522067@56c63e69 [junit4] 2> 706931 INFO (zkConnectionManagerCallback-2835-thread-1-processing-n:127.0.0.1:47815_solr) [n:127.0.0.1:47815_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 706948 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/clusterprops.json [junit4] 2> 706948 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706949 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 706949 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/clusterstate.json [junit4] 2> 706949 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 706949 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/aliases.json [junit4] 2> 706950 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/security.json [junit4] 2> 706956 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 706957 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/72092382110220305-127.0.0.1:47815_solr-n_0000000004 [junit4] 2> 706957 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: data: /solr/overseer_elect/election/72092382110220293-127.0.0.1:48098_solr-n_0000000003 [junit4] 2> 706957 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/72092382110220293-127.0.0.1:48098_solr-n_0000000003 to know if I could be the leader [junit4] 2> 706958 DEBUG (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:47815_solr as DOWN [junit4] 2> 706964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 706964 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47815_solr [junit4] 2> 706976 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 706976 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:47815_solr"} current state version: 0 [junit4] 2> 706976 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:47815_solr [junit4] 2> 706978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706978 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706979 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706979 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/live_nodes [junit4] 2> 706980 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706980 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706980 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706980 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706981 INFO (zkCallback-2791-thread-1-processing-n:127.0.0.1:48098_solr) [n:127.0.0.1:48098_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706981 INFO (zkCallback-2805-thread-1-processing-n:127.0.0.1:53026_solr) [n:127.0.0.1:53026_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706981 INFO (zkCallback-2811-thread-1-processing-n:127.0.0.1:65153_solr) [n:127.0.0.1:65153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706982 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706982 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706982 INFO (zkCallback-2825-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706988 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/live_nodes [junit4] 2> 706988 INFO (zkCallback-2803-thread-1-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706988 INFO (zkCallback-2825-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 706989 INFO (zkCallback-2834-thread-1-processing-n:127.0.0.1:47815_solr) [n:127.0.0.1:47815_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 707078 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 707080 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 707271 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47815.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 707285 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47815.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 707285 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_47815.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@731c01b [junit4] 2> 707289 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [n:127.0.0.1:47815_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-001/node5/. [junit4] 2> 707412 INFO (zkConnectionManagerCallback-2839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 707419 INFO (TEST-MoveReplicaHDFSTest.test-seed#[39BF131140344BF0]) [ ] o.a.s.c.MoveReplicaTest total_jettys: 5 [junit4] 2> 707423 INFO (qtp712352339-6969) [n:127.0.0.1:47815_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> 707424 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 707425 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000002 [junit4] 2> 707425 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 707425 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 707426 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/collection-queue-work [junit4] 2> 707427 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/collection-queue-work [junit4] 2> 707435 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.ZkDistributedQueue Found child node with improper name: qnr-0000000002 [junit4] 2> 707456 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Returning topN elements: [/overseer/collection-queue-work/qn-0000000002, ] [junit4] 2> 707456 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ed1]] [junit4] 2> 707456 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000002] as running [junit4] 2> 707456 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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> 707457 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000002 [junit4] 2> 707457 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_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> 707457 INFO (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection MoveReplicaHDFSTest_coll_true [junit4] 2> 707457 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 [junit4] 2> 707457 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000002] [junit4] 2> 707467 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: [] [junit4] 2> 707467 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: [] [junit4] 2> 707467 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000002] [junit4] 2> 707467 DEBUG (OverseerCollectionConfigSetProcessor-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000002],[] [junit4] 2> 707468 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.Assign Identify nodes using default [junit4] 2> 707468 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/MoveReplicaHDFSTest_coll_true [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707470 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707471 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode:MoveReplicaHDFSTest_coll_true [junit4] 2> 707471 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707471 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707471 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707472 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707472 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/collections [junit4] 2> 707473 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists [junit4] 2> 707474 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 707475 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 707475 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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> 707475 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: MoveReplicaHDFSTest_coll_true [junit4] 2> 707475 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/MoveReplicaHDFSTest_coll_true/state.json [junit4] 2> 707475 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 707475 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 707577 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_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> 707580 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_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:53026_solr [junit4] 2> 707585 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch fired: children: /solr/overseer/queue [junit4] 2> 707586 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch added: children: /solr/overseer/queue [junit4] 2> 707587 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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":"http://127.0.0.1:53026/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 707587 INFO (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_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":"http://127.0.0.1:53026/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 707589 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{}} [junit4] 2> 707589 DEBUG (OverseerStateUpdate-72092382110220297-127.0.0.1:61572_solr-n_0000000000) [n:127.0.0.1:61572_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{"core_node2":{ [junit4] 2> "core":"MoveReplicaHDFSTest_coll_true_shard1_replica_n1", [junit4] 2> "base_url":"http://127.0.0.1:53026/solr", [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 707590 DEBUG (OverseerThreadFactory-1806-thread-2-processing-n:127.0.0.1:61572_solr) [n:127.0.0.1:61572_solr ] o.a.s.c.a.c.CreateCollectionCm [...truncated too long message...] .1:55788 55788 [junit4] 2> 1035553 WARN (Thread-1375) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 26 /solr/collections/MoveReplicaHDFSTest_coll_true/terms/shard2 [junit4] 2> 10 /solr/configs/conf1 [junit4] 2> 9 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/terms/shard1 [junit4] 2> 9 /solr/clusterprops.json [junit4] 2> 9 /solr/aliases.json [junit4] 2> 9 /solr/collections/MoveReplicaHDFSTest_coll_true/terms/shard1 [junit4] 2> 7 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/terms/shard2 [junit4] 2> 5 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/collectionprops.json [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/collections/MoveReplicaHDFSTest_coll_true/collectionprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 212 /solr/collections/MoveReplicaHDFSTest_failed_coll_true/state.json [junit4] 2> 72 /solr/collections/MoveReplicaHDFSTest_coll_true/state.json [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/MoveReplicaHDFSTest_coll_true/leader_elect/shard2/election/72092382110220305-core_node7-n_0000000001 [junit4] 2> 2 /solr/overseer_elect/election/72092382110220300-127.0.0.1:65153_solr-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> 1035555 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 1035562 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 1035666 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 1035666 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data3/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data4/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1827281470-127.0.0.1-1520393596918 (Datanode Uuid 52bb7583-dd19-4c64-9edc-642dc1629dad) service to serv1-vm3/127.0.0.1:46465 [junit4] 2> 1035669 WARN (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called [junit4] 2> 1035676 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 1035779 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 1035779 WARN (DataNode: [[[DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data1/, [DISK]file:/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001/tempDir-002/hdfsBaseDir/data/data2/]] heartbeating to serv1-vm3/127.0.0.1:46465) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1827281470-127.0.0.1-1520393596918 (Datanode Uuid 5e5814af-529c-4e93-8ccc-cd5dc51bd24b) service to serv1-vm3/127.0.0.1:46465 [junit4] 2> 1035803 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@serv1-vm3:0 [junit4] 2> 1035917 INFO (SUITE-MoveReplicaHDFSTest-seed#[39BF131140344BF0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55788 55788 [junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.MoveReplicaHDFSTest_39BF131140344BF0-001 [junit4] 2> Mar 07, 2018 3:38:50 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 27 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@122e6799), locale=nl, timezone=America/Nome [junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=2,free=141662384,total=337620992 [junit4] 2> NOTE: All tests run in this JVM: [HdfsAutoAddReplicasIntegrationTest, ZkStateReaderTest, StatsComponentTest, TestJmxIntegration, TestPerFieldSimilarityWithDefaultOverride, UUIDUpdateProcessorFallbackTest, HdfsUnloadDistributedZkTest, DistributedMLTComponentTest, TestTrieFacet, TestHdfsBackupRestoreCore, LeaderElectionIntegrationTest, TestMergePolicyConfig, TestStandardQParsers, TestUniqueKeyFieldResource, TestSweetSpotSimilarityFactory, OpenCloseCoreStressTest, SchemaVersionSpecificBehaviorTest, TestGenericDistributedQueue, TestCopyFieldCollectionResource, TestStressInPlaceUpdates, TestSuggestSpellingConverter, TestFieldCacheSortRandom, SpellingQueryConverterTest, TestCollationField, TestSQLHandler, CoreAdminCreateDiscoverTest, TestConfigReload, TestTrie, HdfsRecoverLeaseTest, TestNodeAddedTrigger, TestDistribDocBasedVersion, TestFieldResource, TestRangeQuery, TestHighFrequencyDictionaryFactory, TestChildDocTransformer, DistributedDebugComponentTest, HdfsDirectoryFactoryTest, TestLegacyNumericUtils, HighlighterTest, TestRequestStatusCollectionAPI, TestFieldCacheWithThreads, SolrRequestParserTest, SolrIndexSplitterTest, SolrCoreTest, TestManagedSynonymFilterFactory, DirectoryFactoryTest, TestSurroundQueryParser, FullHLLTest, TestJsonRequest, TestLeaderInitiatedRecoveryThread, TermVectorComponentDistributedTest, TestLegacyNumericRangeQueryBuilder, ConnectionReuseTest, TestInPlaceUpdatesDistrib, DistributedQueryElevationComponentTest, TestShardHandlerFactory, TestDistributedStatsComponentCardinality, TestObjectReleaseTracker, TestUtils, VMParamsZkACLAndCredentialsProvidersTest, TestElisionMultitermQuery, MoveReplicaHDFSTest] [junit4] Completed [173/781 (1!)] on J0 in 344.38s, 4 tests, 1 failure, 1 error, 1 skipped <<< FAILURES! [...truncated 48032 lines...] -ecj-javadoc-lint-src: [mkdir] Created dir: /var/tmp/ecj920237444 [ecj-lint] Compiling 1166 source files to /var/tmp/ecj920237444 [ecj-lint] invalid Class-Path header in manifest of jar file: /export/home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar [ecj-lint] invalid Class-Path header in manifest of jar file: /export/home/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar [ecj-lint] ---------- [ecj-lint] 1. ERROR in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/java/org/apache/solr/handler/admin/CollectionsHandler.java (at line 31) [ecj-lint] import java.util.OptionalLong; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.OptionalLong is never used [ecj-lint] ---------- [ecj-lint] 1 problem (1 error) BUILD FAILED /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:618: The following error occurred while executing this line: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/build.xml:101: The following error occurred while executing this line: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build.xml:682: The following error occurred while executing this line: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:2082: The following error occurred while executing this line: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:2121: Compile failed; see the compiler error output for details. Total time: 89 minutes 17 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org