Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/352/
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerRolesTest.testDesignatedOverseerRestarts
Error Message:
Timed out waiting for overseer state change. The current overseer is:
127.0.0.1:43065_solr
Stack Trace:
java.lang.AssertionError: Timed out waiting for overseer state change. The
current overseer is: 127.0.0.1:43065_solr
at
__randomizedtesting.SeedInfo.seed([69D979133652414B:61F86E4C080C5341]:0)
at org.junit.Assert.fail(Assert.java:88)
at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:70)
at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:75)
at
org.apache.solr.cloud.OverseerRolesTest.testDesignatedOverseerRestarts(OverseerRolesTest.java:188)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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
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.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 12838 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> 586962 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/init-core-data-001
[junit4] 2> 586963 WARN
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 586963 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 586964 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 586965 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 6 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001
[junit4] 2> 586965 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 586973 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 586973 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 587073 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36269
[junit4] 2> 587073 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36269
[junit4] 2> 587073 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 36269
[junit4] 2> 587079 INFO (zkConnectionManagerCallback-2843-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587084 INFO (zkConnectionManagerCallback-2845-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587086 INFO (zkConnectionManagerCallback-2847-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587093 WARN (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587093 WARN (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587094 WARN (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587094 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587094 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587094 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587094 WARN (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587095 WARN (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587095 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587096 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587096 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587096 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587096 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587096 WARN (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 587096 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587097 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 587097 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 587097 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587097 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 587101 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587101 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587101 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 587102 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5407dd8d{/solr,null,AVAILABLE}
[junit4] 2> 587104 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587104 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@ebdaadd{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:43065}
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.e.j.s.Server Started @587165ms
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43065}
[junit4] 2> 587105 ERROR (jetty-launcher-2848-thread-6) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@485ee597{/solr,null,AVAILABLE}
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587105 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.552035Z
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1f2a5bf{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:37782}
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.e.j.s.Server Started @587166ms
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37782}
[junit4] 2> 587106 ERROR (jetty-launcher-2848-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587106 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587107 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587107 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.553023Z
[junit4] 2> 587108 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587108 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587108 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 587108 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@24ca1115{/solr,null,AVAILABLE}
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@25250928{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:35611}
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.e.j.s.Server Started @587168ms
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35611}
[junit4] 2> 587109 ERROR (jetty-launcher-2848-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587109 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.555676Z
[junit4] 2> 587111 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 587112 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@55764cc3{/solr,null,AVAILABLE}
[junit4] 2> 587112 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6e2bb14a{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:40453}
[junit4] 2> 587112 INFO (jetty-launcher-2848-thread-2) [ ]
o.e.j.s.Server Started @587172ms
[junit4] 2> 587112 INFO (zkConnectionManagerCallback-2850-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587112 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40453}
[junit4] 2> 587113 ERROR (jetty-launcher-2848-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587113 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587113 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587113 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587113 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587113 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.559214Z
[junit4] 2> 587114 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587114 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587114 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 587114 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587114 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@75bfe29f{/solr,null,AVAILABLE}
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@39fd68f7{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:45552}
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.e.j.s.Server Started @587174ms
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45552}
[junit4] 2> 587115 ERROR (jetty-launcher-2848-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587115 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.561608Z
[junit4] 2> 587124 INFO (zkConnectionManagerCallback-2852-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587125 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587127 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 587127 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 587127 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 587148 INFO (zkConnectionManagerCallback-2858-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587149 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3e835f93{/solr,null,AVAILABLE}
[junit4] 2> 587149 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587150 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6ddfa13a{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:41608}
[junit4] 2> 587150 INFO (jetty-launcher-2848-thread-4) [ ]
o.e.j.s.Server Started @587210ms
[junit4] 2> 587150 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41608}
[junit4] 2> 587151 ERROR (jetty-launcher-2848-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 587151 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 587151 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 587151 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 587151 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 587151 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:11.597277Z
[junit4] 2> 587157 INFO (zkConnectionManagerCallback-2856-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587158 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587196 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
0x104352a22fa0006, likely client has closed socket
[junit4] 2> 587198 INFO (zkConnectionManagerCallback-2854-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587199 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587218 INFO (zkConnectionManagerCallback-2861-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587219 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 587299 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 587300 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 587309 WARN (jetty-launcher-2848-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@1d476659[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587340 WARN (jetty-launcher-2848-thread-3) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@1b8a2c66[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587344 WARN (jetty-launcher-2848-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@90d5cef[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587346 INFO (jetty-launcher-2848-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 587349 WARN (jetty-launcher-2848-thread-3) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@5bd7f457[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587351 INFO (jetty-launcher-2848-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 587351 INFO (zkConnectionManagerCallback-2874-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587357 INFO (zkConnectionManagerCallback-2878-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587357 INFO (zkConnectionManagerCallback-2880-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587406 INFO (zkConnectionManagerCallback-2882-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587418 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:45552_solr
[junit4] 2> 587420 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) starting
[junit4] 2> 587450 INFO (zkConnectionManagerCallback-2891-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587454 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 587462 INFO
(OverseerStateUpdate-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:45552_solr
[junit4] 2> 587486 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 587489 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45552_solr
[junit4] 2> 587490 WARN (jetty-launcher-2848-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@5c19d10[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587494 WARN (jetty-launcher-2848-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@c42c995[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587497 INFO (jetty-launcher-2848-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 587504 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 587504 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:40453_solr as DOWN
[junit4] 2> 587504 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 587507 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 587507 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40453_solr
[junit4] 2> 587511 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 587511 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 587515 INFO (zkCallback-2879-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 587523 INFO (zkConnectionManagerCallback-2897-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587523 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 587568 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 587573 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45552.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 587575 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 587583 WARN (jetty-launcher-2848-thread-6) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@37efbe7b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587590 INFO (zkConnectionManagerCallback-2900-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587597 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 587603 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:35611_solr as DOWN
[junit4] 2> 587604 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 587604 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35611_solr
[junit4] 2> 587606 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 587606 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 587606 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 587616 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45552.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 587617 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45552.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 587618 INFO (jetty-launcher-2848-thread-3)
[n:127.0.0.1:45552_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node3/.
[junit4] 2> 587646 INFO (zkConnectionManagerCallback-2905-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587649 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 587651 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 587667 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 587671 INFO (zkCallback-2899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 587702 WARN (jetty-launcher-2848-thread-6) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@5c546bc3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587704 INFO (jetty-launcher-2848-thread-6) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 587718 WARN (jetty-launcher-2848-thread-4) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@6dade62d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587761 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 587814 INFO (zkConnectionManagerCallback-2920-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587880 WARN (jetty-launcher-2848-thread-4) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@27b3437a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 587882 INFO (jetty-launcher-2848-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 587886 INFO (zkConnectionManagerCallback-2914-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587888 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 587890 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 587896 INFO (zkConnectionManagerCallback-2923-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587903 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 587915 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:43065_solr as DOWN
[junit4] 2> 587916 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 587916 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43065_solr
[junit4] 2> 587919 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587919 INFO (zkCallback-2899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587919 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587920 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587922 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 587933 INFO (zkConnectionManagerCallback-2927-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587936 INFO (zkCallback-2913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587939 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40453.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 587940 INFO (zkCallback-2904-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587948 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 587964 INFO (zkConnectionManagerCallback-2932-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 587971 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 587976 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:41608_solr as DOWN
[junit4] 2> 587977 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 587977 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41608_solr
[junit4] 2> 587980 INFO (zkCallback-2913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587980 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587980 INFO (zkCallback-2904-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587981 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587981 INFO (zkCallback-2899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587981 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587981 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 587981 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 588017 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35611.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588017 INFO (zkConnectionManagerCallback-2939-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588019 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 588021 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 588040 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40453.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588040 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40453.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588049 INFO (jetty-launcher-2848-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node4/.
[junit4] 2> 588050 WARN (jetty-launcher-2848-thread-5) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@513bf441[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 588053 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 588054 INFO (zkConnectionManagerCallback-2945-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588056 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 588057 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 588058 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 588110 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 588111 WARN (jetty-launcher-2848-thread-5) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
SslContextFactory@74eec250[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 588113 INFO (jetty-launcher-2848-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36269/solr
[junit4] 2> 588154 INFO (zkConnectionManagerCallback-2952-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588163 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35611.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588163 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35611.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588176 INFO (jetty-launcher-2848-thread-1)
[n:127.0.0.1:35611_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node2/.
[junit4] 2> 588185 INFO (zkConnectionManagerCallback-2954-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588203 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 588217 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43065.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588225 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:37782_solr as DOWN
[junit4] 2> 588231 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 588231 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37782_solr
[junit4] 2> 588235 INFO (zkCallback-2913-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588235 INFO (zkCallback-2904-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588235 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588235 INFO (zkCallback-2899-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588237 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588237 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588237 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588238 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588238 INFO (zkCallback-2938-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588240 INFO (zkCallback-2944-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588250 INFO (zkCallback-2953-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 588279 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41608.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588279 INFO (zkConnectionManagerCallback-2961-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588294 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (6)
[junit4] 2> 588295 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36269/solr ready
[junit4] 2> 588305 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 588322 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43065.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588322 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43065.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588348 INFO (jetty-launcher-2848-thread-6)
[n:127.0.0.1:43065_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node6/.
[junit4] 2> 588365 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41608.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588365 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41608.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588368 INFO (jetty-launcher-2848-thread-4)
[n:127.0.0.1:41608_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node1/.
[junit4] 2> 588429 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37782.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588466 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37782.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588466 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37782.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1071ed33
[junit4] 2> 588469 INFO (jetty-launcher-2848-thread-5)
[n:127.0.0.1:37782_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_69D979133652414B-001/tempDir-001/node5/.
[junit4] 2> 588940 INFO (zkConnectionManagerCallback-2967-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 588942 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6)
[junit4] 2> 588944 INFO
(SUITE-OverseerRolesTest-seed#[69D979133652414B]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36269/solr ready
[junit4] 2> 589041 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
[junit4] 2> 589042 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:45552_solr
[junit4] 2> 589043 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 589052 INFO (qtp327211028-7794) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:45552_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589056 INFO
(OverseerThreadFactory-2600-thread-1-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:45552_solr
[junit4] 2> 589061 INFO (qtp327211028-7794) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:45552_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=9
[junit4] 2> 589063 INFO (qtp327211028-7796) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:40453_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589066 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_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> 589068 INFO
(OverseerThreadFactory-2600-thread-2-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:40453_solr
[junit4] 2> 589079 INFO (qtp327211028-7796) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:40453_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=16
[junit4] 2> 589081 INFO (qtp327211028-7809) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:35611_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589085 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589087 INFO
(OverseerThreadFactory-2600-thread-3-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:35611_solr
[junit4] 2> 589090 INFO (qtp327211028-7809) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:35611_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=9
[junit4] 2> 589091 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:43065_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589097 INFO
(OverseerThreadFactory-2600-thread-4-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:43065_solr
[junit4] 2> 589097 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589100 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43065_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 589102 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:41608_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589106 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589106 INFO
(OverseerThreadFactory-2600-thread-5-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:41608_solr
[junit4] 2> 589109 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:41608_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 589111 INFO (qtp327211028-7793) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:37782_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589114 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589114 INFO
(OverseerThreadFactory-2600-thread-5-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:37782_solr
[junit4] 2> 589136 INFO (qtp327211028-7793) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:37782_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=26
[junit4] 2> 589142 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:35611_solr
[junit4] 2> 589144 INFO (qtp327211028-7794) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:35611_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589149 INFO
(OverseerCollectionConfigSetProcessor-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589149 INFO
(OverseerThreadFactory-2600-thread-5-processing-n:127.0.0.1:45552_solr)
[n:127.0.0.1:45552_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:35611_solr
[junit4] 2> 589152 INFO (qtp327211028-7794) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:35611_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 589154 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:35611_solr
[junit4] 2> 589154 INFO (Thread-1722) [ ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
73241949036478476-127.0.0.1:45552_solr-n_0000000000 overseer designates are
[127.0.0.1:35611_solr]
[junit4] 2> 589155 INFO (Thread-1722) [ ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[73241949036478476-127.0.0.1:45552_solr-n_0000000000,
73241949036478475-127.0.0.1:40453_solr-n_0000000001,
73241949036478479-127.0.0.1:35611_solr-n_0000000002,
73241949036478483-127.0.0.1:43065_solr-n_0000000003,
73241949036478485-127.0.0.1:41608_solr-n_0000000004,
73241949036478489-127.0.0.1:37782_solr-n_0000000005]
[junit4] 2> 589155 INFO (Thread-1722) [ ]
o.a.s.c.OverseerNodePrioritizer asking node
73241949036478479-127.0.0.1:35611_solr-n_0000000002 to come join election at
head
[junit4] 2> 589162 INFO (qtp327211028-7809) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478479-127.0.0.1:35611_solr-n_0000000002&wt=javabin&version=2}
status=0 QTime=4
[junit4] 2> 589163 INFO (Thread-1722) [ ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
73241949036478475-127.0.0.1:40453_solr-n_0000000001 to rejoin election
[junit4] 2> 589169 INFO (qtp1027188387-7800) [n:127.0.0.1:40453_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478475-127.0.0.1:40453_solr-n_0000000001&wt=javabin&version=2}
status=0 QTime=4
[junit4] 2> 589172 INFO
(OverseerStateUpdate-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"73241949036478476-127.0.0.1:45552_solr-n_0000000000"}
127.0.0.1:45552_solr
[junit4] 2> 589173 INFO
(OverseerStateUpdate-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[n:127.0.0.1:45552_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:45552_solr
[junit4] 2> 589174 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) am exiting, but I'm
still the leader
[junit4] 2> 589175 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589176 INFO
(OverseerAutoScalingTriggerThread-73241949036478476-127.0.0.1:45552_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589176 INFO (zkCallback-2899-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35611_solr
[junit4] 2> 589178 INFO (zkCallback-2899-thread-2)
[n:127.0.0.1:35611_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478479-127.0.0.1:35611_solr-n_0000000001) starting
[junit4] 2> 589179 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589179 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589180 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589186 INFO
(OverseerStateUpdate-73241949036478479-127.0.0.1:35611_solr-n_0000000001)
[n:127.0.0.1:35611_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:35611_solr
[junit4] 2> 589256 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest ### Adding another overseer designate
127.0.0.1:41608_solr
[junit4] 2> 589257 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:41608_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589261 INFO
(OverseerThreadFactory-2639-thread-1-processing-n:127.0.0.1:35611_solr)
[n:127.0.0.1:35611_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:41608_solr
[junit4] 2> 589265 INFO (qtp327211028-7811) [n:127.0.0.1:35611_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:41608_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 589267 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:35611_solr
[junit4] 2> 589267 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 589267 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=796290575
[junit4] 2> 589267 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:35611_solr
[junit4] 2> 589268 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 589268 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:35611_solr as DOWN
[junit4] 2> 589272 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 589272 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@368c53fb: rootName =
solr_35611, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@84ad940
[junit4] 2> 589283 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 589283 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@353f925d: rootName =
solr_35611, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@6a5926c6
[junit4] 2> 589289 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 589289 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3667e706: rootName =
solr_35611, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3125e2b1
[junit4] 2> 589289 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 589291 INFO (closeThreadPool-2971-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478479-127.0.0.1:35611_solr-n_0000000001) closing
[junit4] 2> 589291 INFO
(OverseerStateUpdate-73241949036478479-127.0.0.1:35611_solr-n_0000000001)
[n:127.0.0.1:35611_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:35611_solr
[junit4] 2> 589292 INFO
(OverseerAutoScalingTriggerThread-73241949036478479-127.0.0.1:35611_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589298 INFO (closeThreadPool-2971-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478479-127.0.0.1:35611_solr-n_0000000001) closing
[junit4] 2> 589298 INFO (zkCallback-2938-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589298 INFO (zkCallback-2944-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589299 INFO (zkCallback-2953-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589299 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478479-127.0.0.1:35611_solr-n_0000000001) closing
[junit4] 2> 589299 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589299 INFO (zkCallback-2881-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589299 INFO (zkCallback-2904-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589299 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589300 INFO (zkCallback-2890-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589300 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43065_solr
[junit4] 2> 589301 INFO (zkCallback-2922-thread-1)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) starting
[junit4] 2> 589302 INFO (zkCallback-2966-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589303 INFO (zkCallback-2922-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589304 INFO (zkCallback-2960-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
[junit4] 2> 589304 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@25250928{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:0}
[junit4] 2> 589305 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@24ca1115{/solr,null,UNAVAILABLE}
[junit4] 2> 589305 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 589306 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:41608_solr
[junit4] 2> 589309 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43065_solr
[junit4] 2> 589312 INFO
(OverseerCollectionConfigSetProcessor-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[n:127.0.0.1:43065_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 73241949036478483-127.0.0.1:43065_solr-n_0000000003 overseer
designates are [127.0.0.1:35611_solr, 127.0.0.1:41608_solr]
[junit4] 2> 589312 INFO
(OverseerCollectionConfigSetProcessor-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[n:127.0.0.1:43065_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[73241949036478483-127.0.0.1:43065_solr-n_0000000003,
73241949036478485-127.0.0.1:41608_solr-n_0000000004,
73241949036478489-127.0.0.1:37782_solr-n_0000000005,
73241949036478475-127.0.0.1:40453_solr-n_0000000007,
73241949036478476-127.0.0.1:45552_solr-n_0000000008]
[junit4] 2> 589315 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"73241949036478483-127.0.0.1:43065_solr-n_0000000003"}
127.0.0.1:43065_solr
[junit4] 2> 589316 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43065_solr
[junit4] 2> 589319 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) am exiting, but I'm
still the leader
[junit4] 2> 589321 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589321 INFO
(OverseerAutoScalingTriggerThread-73241949036478483-127.0.0.1:43065_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589321 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41608_solr
[junit4] 2> 589325 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589325 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589325 INFO (zkCallback-2931-thread-1)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) starting
[junit4] 2> 589326 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589338 INFO
(OverseerStateUpdate-73241949036478485-127.0.0.1:41608_solr-n_0000000004)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:41608_solr
[junit4] 2> 589408 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest ### Adding last overseer designate
127.0.0.1:43065_solr
[junit4] 2> 589411 INFO (qtp142361391-7780) [n:127.0.0.1:37782_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:43065_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589415 INFO
(OverseerThreadFactory-2648-thread-1-processing-n:127.0.0.1:41608_solr)
[n:127.0.0.1:41608_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:43065_solr
[junit4] 2> 589418 INFO (qtp142361391-7780) [n:127.0.0.1:37782_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43065_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 589419 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:41608_solr
[junit4] 2> 589419 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 589420 INFO (qtp142361391-7781) [n:127.0.0.1:37782_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:41608_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 589424 INFO
(OverseerCollectionConfigSetProcessor-73241949036478485-127.0.0.1:41608_solr-n_0000000004)
[n:127.0.0.1:41608_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000016 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 589424 INFO
(OverseerThreadFactory-2648-thread-2-processing-n:127.0.0.1:41608_solr)
[n:127.0.0.1:41608_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:41608_solr
[junit4] 2> 589426 INFO (Thread-1725) [ ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
73241949036478485-127.0.0.1:41608_solr-n_0000000004 overseer designates are
[127.0.0.1:35611_solr, 127.0.0.1:43065_solr]
[junit4] 2> 589426 INFO (Thread-1725) [ ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[73241949036478485-127.0.0.1:41608_solr-n_0000000004,
73241949036478489-127.0.0.1:37782_solr-n_0000000005,
73241949036478475-127.0.0.1:40453_solr-n_0000000007,
73241949036478476-127.0.0.1:45552_solr-n_0000000008,
73241949036478483-127.0.0.1:43065_solr-n_0000000009]
[junit4] 2> 589426 INFO (Thread-1725) [ ]
o.a.s.c.OverseerNodePrioritizer asking node
73241949036478483-127.0.0.1:43065_solr-n_0000000009 to come join election at
head
[junit4] 2> 589427 INFO (qtp142361391-7781) [n:127.0.0.1:37782_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:41608_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 589427 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:43065_solr
[junit4] 2> 589431 INFO (qtp2068907279-7790) [n:127.0.0.1:43065_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589431 INFO (qtp2068907279-7790) [n:127.0.0.1:43065_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589431 INFO (qtp2068907279-7790) [n:127.0.0.1:43065_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589432 INFO (qtp2068907279-7790) [n:127.0.0.1:43065_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589433 INFO (qtp2068907279-7790) [n:127.0.0.1:43065_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478483-127.0.0.1:43065_solr-n_0000000009&wt=javabin&version=2}
status=0 QTime=3
[junit4] 2> 589433 INFO (Thread-1725) [ ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
73241949036478489-127.0.0.1:37782_solr-n_0000000005 to rejoin election
[junit4] 2> 589445 INFO (qtp142361391-7797) [n:127.0.0.1:37782_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478489-127.0.0.1:37782_solr-n_0000000005&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 589447 INFO
(OverseerStateUpdate-73241949036478485-127.0.0.1:41608_solr-n_0000000004)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"73241949036478485-127.0.0.1:41608_solr-n_0000000004"}
127.0.0.1:41608_solr
[junit4] 2> 589448 INFO
(OverseerStateUpdate-73241949036478485-127.0.0.1:41608_solr-n_0000000004)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:41608_solr
[junit4] 2> 589449 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) am exiting, but I'm
still the leader
[junit4] 2> 589450 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000003) closing
[junit4] 2> 589450 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589451 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43065_solr
[junit4] 2> 589451 INFO
(OverseerAutoScalingTriggerThread-73241949036478485-127.0.0.1:41608_solr-n_0000000004)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589466 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589466 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589467 INFO (zkCallback-2922-thread-1)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) starting
[junit4] 2> 589467 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589472 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000005)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43065_solr
[junit4] 2> 589530 INFO (qtp142361391-7779) [n:127.0.0.1:37782_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:41608_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589534 INFO
(OverseerThreadFactory-2652-thread-1-processing-n:127.0.0.1:43065_solr)
[n:127.0.0.1:43065_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:41608_solr
[junit4] 2> 589538 INFO (qtp142361391-7779) [n:127.0.0.1:37782_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:41608_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 589539 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:43065_solr
[junit4] 2> 589542 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000005)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"73241949036478483-127.0.0.1:43065_solr-n_0000000005"}
127.0.0.1:43065_solr
[junit4] 2> 589543 INFO
(OverseerStateUpdate-73241949036478483-127.0.0.1:43065_solr-n_0000000005)
[n:127.0.0.1:43065_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43065_solr
[junit4] 2> 589544 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) am exiting, but I'm
still the leader
[junit4] 2> 589545 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) closing
[junit4] 2> 589546 INFO
(OverseerAutoScalingTriggerThread-73241949036478483-127.0.0.1:43065_solr-n_0000000005)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589546 INFO (zkCallback-2879-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40453_solr
[junit4] 2> 589547 INFO (zkCallback-2879-thread-2)
[n:127.0.0.1:40453_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) starting
[junit4] 2> 589550 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) closing
[junit4] 2> 589550 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) closing
[junit4] 2> 589551 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) closing
[junit4] 2> 589554 INFO
(OverseerStateUpdate-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:40453_solr
[junit4] 2> 589556 INFO
(OverseerCollectionConfigSetProcessor-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 73241949036478475-127.0.0.1:40453_solr-n_0000000007 overseer
designates are [127.0.0.1:35611_solr, 127.0.0.1:43065_solr,
127.0.0.1:41608_solr]
[junit4] 2> 589557 INFO
(OverseerCollectionConfigSetProcessor-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[73241949036478475-127.0.0.1:40453_solr-n_0000000007,
73241949036478476-127.0.0.1:45552_solr-n_0000000008,
73241949036478489-127.0.0.1:37782_solr-n_0000000011,
73241949036478485-127.0.0.1:41608_solr-n_0000000012,
73241949036478483-127.0.0.1:43065_solr-n_0000000013]
[junit4] 2> 589557 INFO
(OverseerCollectionConfigSetProcessor-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.OverseerNodePrioritizer asking node
73241949036478485-127.0.0.1:41608_solr-n_0000000012 to come join election at
head
[junit4] 2> 589562 INFO (qtp397781386-7831) [n:127.0.0.1:41608_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589562 INFO (qtp397781386-7831) [n:127.0.0.1:41608_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589562 INFO (qtp397781386-7831) [n:127.0.0.1:41608_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589563 INFO (zkCallback-2922-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478483-127.0.0.1:43065_solr-n_0000000005) closing
[junit4] 2> 589563 INFO (qtp397781386-7831) [n:127.0.0.1:41608_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589564 INFO (qtp397781386-7831) [n:127.0.0.1:41608_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478485-127.0.0.1:41608_solr-n_0000000012&wt=javabin&version=2}
status=0 QTime=3
[junit4] 2> 589565 INFO
(OverseerCollectionConfigSetProcessor-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.OverseerNodePrioritizer asking the old
first in line 73241949036478476-127.0.0.1:45552_solr-n_0000000008 to rejoin
election
[junit4] 2> 589568 INFO (qtp380619087-7821) [n:127.0.0.1:45552_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589568 INFO (qtp380619087-7821) [n:127.0.0.1:45552_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589568 INFO (qtp380619087-7821) [n:127.0.0.1:45552_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589568 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589569 INFO (qtp380619087-7821) [n:127.0.0.1:45552_solr ]
o.a.s.c.Overseer Overseer
(id=73241949036478476-127.0.0.1:45552_solr-n_0000000000) closing
[junit4] 2> 589570 INFO (qtp380619087-7821) [n:127.0.0.1:45552_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73241949036478476-127.0.0.1:45552_solr-n_0000000008&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 589572 INFO
(OverseerStateUpdate-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"73241949036478475-127.0.0.1:40453_solr-n_0000000007"}
127.0.0.1:40453_solr
[junit4] 2> 589573 INFO
(OverseerStateUpdate-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[n:127.0.0.1:40453_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40453_solr
[junit4] 2> 589574 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) am exiting, but I'm
still the leader
[junit4] 2> 589575 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000004) closing
[junit4] 2> 589575 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) closing
[junit4] 2> 589575 INFO
(OverseerAutoScalingTriggerThread-73241949036478475-127.0.0.1:40453_solr-n_0000000007)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 589575 INFO (zkCallback-2931-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41608_solr
[junit4] 2> 589578 INFO (zkCallback-2931-thread-1)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Overseer
(id=73241949036478485-127.0.0.1:41608_solr-n_0000000008) starting
[junit4] 2> 589578 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) closing
[junit4] 2> 589579 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) closing
[junit4] 2> 589579 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=73241949036478475-127.0.0.1:40453_solr-n_0000000007) closing
[junit4] 2> 589585 INFO
(OverseerStateUpdate-73241949036478485-127.0.0.1:41608_solr-n_0000000008)
[n:127.0.0.1:41608_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:41608_solr
[junit4] 2> 590644 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:41608_solr
[junit4] 2> 590644 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 590645 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 590645 WARN
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 590646 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 35611 try number 1 ...
[junit4] 2> 590646 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git:
c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
[junit4] 2> 590647 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 590647 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 590647 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 590648 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@389683ba{/solr,null,AVAILABLE}
[junit4] 2> 590648 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6d5d18e6{HTTP/1.1,[http/1.1,
h2c]}{127.0.0.1:35611}
[junit4] 2> 590648 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.e.j.s.Server Started @590708ms
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35611}
[junit4] 2> 590649 ERROR
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
9.0.0
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 590649 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[69D979133652414B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-05-04T02:31:15.095611Z
[junit4] 2> 590654 INFO (zkConnectionManagerCallb
[...truncated too long message...]
erty disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null879652724
[copy] Copying 239 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null879652724
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null879652724
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene1-us-west
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 33ms :: artifacts dl 3ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 9 | 0 | 0 | 0 || 9 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 127 minutes 22 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
at
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at
hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no
matches found within 10000
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1835)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
