Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/960/
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerRolesTest.testOverseerRole
Error Message:
Timed out waiting for overseer state change
Stack Trace:
java.lang.AssertionError: Timed out waiting for overseer state change
at
__randomizedtesting.SeedInfo.seed([FC64A1B454A42F26:1DAF5C206F1719F7]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:63)
at
org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:143)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13895 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/init-core-data-001
[junit4] 2> 1627448 WARN
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 1627448 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1627451 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1627451 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1627452 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/tempDir-001
[junit4] 2> 1627452 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1627453 INFO (Thread-3311) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1627453 INFO (Thread-3311) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1627455 ERROR (Thread-3311) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 1627553 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:45639
[junit4] 2> 1627555 INFO (zkConnectionManagerCallback-6722-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627561 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1627562 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1627564 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1627565 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1627566 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1627566 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1627566 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1627567 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@480d7fa4{/solr,null,AVAILABLE}
[junit4] 2> 1627567 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@124a8c71{HTTP/1.1,[http/1.1]}{127.0.0.1:46072}
[junit4] 2> 1627567 INFO (jetty-launcher-6719-thread-2) [ ]
o.e.j.s.Server Started @1627630ms
[junit4] 2> 1627567 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46072}
[junit4] 2> 1627568 ERROR (jetty-launcher-6719-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1627568 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1627568 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1627568 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1627568 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1627568 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-10-19T15:39:46.196Z
[junit4] 2> 1627572 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1627572 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1627572 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1627572 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@33dfa3a{/solr,null,AVAILABLE}
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3a46ffac{HTTP/1.1,[http/1.1]}{127.0.0.1:43707}
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.e.j.s.Server Started @1627636ms
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43707}
[junit4] 2> 1627573 ERROR (jetty-launcher-6719-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1627573 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-10-19T15:39:46.201Z
[junit4] 2> 1627577 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1627577 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1627577 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1627577 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2a5a2ba3{/solr,null,AVAILABLE}
[junit4] 2> 1627591 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2338c131{HTTP/1.1,[http/1.1]}{127.0.0.1:33222}
[junit4] 2> 1627591 INFO (jetty-launcher-6719-thread-4) [ ]
o.e.j.s.Server Started @1627654ms
[junit4] 2> 1627591 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33222}
[junit4] 2> 1627591 INFO (zkConnectionManagerCallback-6726-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627591 ERROR (jetty-launcher-6719-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1627592 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-10-19T15:39:46.220Z
[junit4] 2> 1627592 INFO (zkConnectionManagerCallback-6724-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627594 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1627594 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1627594 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1627594 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1627595 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@549cf41e{/solr,null,AVAILABLE}
[junit4] 2> 1627603 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@75268049{HTTP/1.1,[http/1.1]}{127.0.0.1:40594}
[junit4] 2> 1627603 INFO (jetty-launcher-6719-thread-3) [ ]
o.e.j.s.Server Started @1627666ms
[junit4] 2> 1627603 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40594}
[junit4] 2> 1627604 ERROR (jetty-launcher-6719-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1627604 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1627609 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.6.0
[junit4] 2> 1627609 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1627609 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1627609 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-10-19T15:39:46.237Z
[junit4] 2> 1627620 INFO (zkConnectionManagerCallback-6728-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627621 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1627639 INFO (zkConnectionManagerCallback-6730-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627642 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1627741 INFO (jetty-launcher-6719-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45639/solr
[junit4] 2> 1627743 INFO (zkConnectionManagerCallback-6734-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627762 INFO (jetty-launcher-6719-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45639/solr
[junit4] 2> 1627763 INFO (zkConnectionManagerCallback-6737-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627787 INFO (zkConnectionManagerCallback-6740-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627795 INFO (zkConnectionManagerCallback-6744-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627809 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40594_solr
[junit4] 2> 1627811 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000000) starting
[junit4] 2> 1627832 INFO (zkConnectionManagerCallback-6751-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627835 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:45639/solr ready
[junit4] 2> 1627841 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000000)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:40594_solr
[junit4] 2> 1627848 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40594_solr
[junit4] 2> 1627851 INFO (zkCallback-6743-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1627878 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1627880 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1627887 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1627888 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33222_solr
[junit4] 2> 1627893 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1627899 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1627915 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1627920 INFO (jetty-launcher-6719-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45639/solr
[junit4] 2> 1627921 INFO (zkCallback-6743-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1627922 INFO (zkConnectionManagerCallback-6761-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627935 INFO (zkConnectionManagerCallback-6758-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627936 INFO (zkConnectionManagerCallback-6763-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1627937 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1627940 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:45639/solr ready
[junit4] 2> 1627941 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1627952 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1627986 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1627986 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46072_solr
[junit4] 2> 1627995 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1627995 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1627995 INFO (zkCallback-6757-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1627995 INFO (zkCallback-6743-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1627996 INFO (zkCallback-6762-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1628007 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33222.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628009 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40594.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628020 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33222.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628020 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33222.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628022 INFO (jetty-launcher-6719-thread-4)
[n:127.0.0.1:33222_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/tempDir-001/node4/.
[junit4] 2> 1628022 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40594.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628022 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40594.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628024 INFO (jetty-launcher-6719-thread-3)
[n:127.0.0.1:40594_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/tempDir-001/node3/.
[junit4] 2> 1628026 INFO (zkConnectionManagerCallback-6771-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628027 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1628029 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:45639/solr ready
[junit4] 2> 1628029 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1628064 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46072.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628078 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46072.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628079 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46072.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628081 INFO (jetty-launcher-6719-thread-2)
[n:127.0.0.1:46072_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/tempDir-001/node2/.
[junit4] 2> 1628403 INFO (jetty-launcher-6719-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45639/solr
[junit4] 2> 1628404 INFO (zkConnectionManagerCallback-6776-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628407 INFO (zkConnectionManagerCallback-6778-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628416 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1628423 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1628423 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43707_solr
[junit4] 2> 1628425 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628425 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628431 INFO (zkCallback-6743-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628446 INFO (zkCallback-6757-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628446 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628446 INFO (zkCallback-6762-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628453 INFO (zkCallback-6770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1628462 INFO (zkConnectionManagerCallback-6785-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628465 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1628467 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:45639/solr ready
[junit4] 2> 1628467 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1628491 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43707.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628505 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43707.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628505 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43707.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@53d4edc3
[junit4] 2> 1628507 INFO (jetty-launcher-6719-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001/tempDir-001/node1/.
[junit4] 2> 1628576 INFO (zkConnectionManagerCallback-6788-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628580 INFO (zkConnectionManagerCallback-6793-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1628582 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1628583 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45639/solr ready
[junit4] 2> 1628632 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
[junit4] 2> 1628633 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:40594_solr
[junit4] 2> 1628633 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 1628634 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:46072_solr
[junit4] 2> 1628638 INFO (qtp382860459-19955) [n:127.0.0.1:33222_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:46072_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1628651 INFO
(OverseerThreadFactory-6764-thread-1-processing-n:127.0.0.1:40594_solr)
[n:127.0.0.1:40594_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:46072_solr
[junit4] 2> 1628655 INFO (Thread-3348) [ ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
72129576976449544-127.0.0.1:40594_solr-n_0000000000 overseer designates are
[127.0.0.1:46072_solr]
[junit4] 2> 1628656 INFO (qtp382860459-19955) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:46072_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=17
[junit4] 2> 1628656 INFO (Thread-3348) [ ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449544-127.0.0.1:40594_solr-n_0000000000,
72129576976449542-127.0.0.1:33222_solr-n_0000000001,
72129576976449548-127.0.0.1:46072_solr-n_0000000002,
72129576976449551-127.0.0.1:43707_solr-n_0000000003]
[junit4] 2> 1628657 INFO (Thread-3348) [ ]
o.a.s.c.OverseerNodePrioritizer asking node
72129576976449548-127.0.0.1:46072_solr-n_0000000002 to come join election at
head
[junit4] 2> 1628658 INFO (qtp147154447-19933) [n:127.0.0.1:46072_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449548-127.0.0.1:46072_solr-n_0000000002&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628659 INFO (Thread-3348) [ ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
72129576976449542-127.0.0.1:33222_solr-n_0000000001 to rejoin election
[junit4] 2> 1628660 INFO (qtp382860459-19950) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449542-127.0.0.1:33222_solr-n_0000000001&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628663 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000000)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449544-127.0.0.1:40594_solr-n_0000000000"}
127.0.0.1:40594_solr
[junit4] 2> 1628664 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000000)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40594_solr
[junit4] 2> 1628664 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000000) am exiting, but I'm
still the leader
[junit4] 2> 1628665 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449544-127.0.0.1:40594_solr-n_0000000000) closing
[junit4] 2> 1628667 WARN
(OverseerAutoScalingTriggerThread-72129576976449544-127.0.0.1:40594_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1628667 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33222_solr
[junit4] 2> 1628669 INFO (zkCallback-6736-thread-1)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000001) starting
[junit4] 2> 1628676 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:33222_solr
[junit4] 2> 1628678 INFO
(OverseerCollectionConfigSetProcessor-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[n:127.0.0.1:33222_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 72129576976449542-127.0.0.1:33222_solr-n_0000000001 overseer
designates are [127.0.0.1:46072_solr]
[junit4] 2> 1628678 INFO
(OverseerCollectionConfigSetProcessor-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[n:127.0.0.1:33222_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449542-127.0.0.1:33222_solr-n_0000000001,
72129576976449548-127.0.0.1:46072_solr-n_0000000002,
72129576976449551-127.0.0.1:43707_solr-n_0000000003]
[junit4] 2> 1628689 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449542-127.0.0.1:33222_solr-n_0000000001"}
127.0.0.1:33222_solr
[junit4] 2> 1628689 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33222_solr
[junit4] 2> 1628692 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000001) am exiting, but I'm
still the leader
[junit4] 2> 1628701 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449542-127.0.0.1:33222_solr-n_0000000001) closing
[junit4] 2> 1628701 WARN
(OverseerAutoScalingTriggerThread-72129576976449542-127.0.0.1:33222_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1628713 INFO (zkCallback-6762-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46072_solr
[junit4] 2> 1628713 WARN (ScheduledTrigger-6788-thread-2) [ ]
o.a.s.c.a.TriggerBase Exception updating trigger state
'/autoscaling/triggerState/.auto_add_replicas'
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Object.wait(Object.java:502) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn.submitRequest(ClientCnxn.java:1409)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1328)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$setData$6(SolrZkClient.java:353)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:353)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkDistribStateManager.setData(ZkDistribStateManager.java:146)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.TriggerBase.saveState(TriggerBase.java:236)
[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:599)
[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1628715 INFO (zkCallback-6762-thread-1)
[n:127.0.0.1:46072_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449548-127.0.0.1:46072_solr-n_0000000002) starting
[junit4] 2> 1628723 INFO
(OverseerStateUpdate-72129576976449548-127.0.0.1:46072_solr-n_0000000002)
[n:127.0.0.1:46072_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:46072_solr
[junit4] 2> 1628759 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest ### Adding another overseer designate
127.0.0.1:43707_solr
[junit4] 2> 1628762 INFO (qtp382860459-19951) [n:127.0.0.1:33222_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:43707_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1628765 INFO
(OverseerThreadFactory-6794-thread-1-processing-n:127.0.0.1:46072_solr)
[n:127.0.0.1:46072_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:43707_solr
[junit4] 2> 1628768 INFO (qtp382860459-19951) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43707_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 1628769 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:46072_solr
[junit4] 2> 1628770 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 1628770 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 46072
[junit4] 2> 1628770 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1372000510
[junit4] 2> 1628770 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1628770 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@36f1f9b1: rootName =
solr_46072, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5c60dc04
[junit4] 2> 1628782 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1628783 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3d1c471c: rootName =
solr_46072, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1c55b8df
[junit4] 2> 1628788 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1628788 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@47ea024c: rootName =
solr_46072, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2cfd0592
[junit4] 2> 1628790 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:46072_solr
[junit4] 2> 1628791 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1628792 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628792 INFO (zkCallback-6757-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628792 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628792 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628792 INFO (zkCallback-6743-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628792 INFO (zkCallback-6762-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628794 INFO (zkCallback-6784-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628794 INFO (zkCallback-6770-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628795 INFO (zkCallback-6792-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1628795 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.Overseer Overseer
(id=72129576976449548-127.0.0.1:46072_solr-n_0000000002) closing
[junit4] 2> 1628795 INFO
(OverseerStateUpdate-72129576976449548-127.0.0.1:46072_solr-n_0000000002)
[n:127.0.0.1:46072_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:46072_solr
[junit4] 2> 1628796 WARN
(OverseerAutoScalingTriggerThread-72129576976449548-127.0.0.1:46072_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1628800 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43707_solr
[junit4] 2> 1628802 INFO (zkCallback-6777-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449551-127.0.0.1:43707_solr-n_0000000003) starting
[junit4] 2> 1628802 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@124a8c71{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1628803 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@480d7fa4{/solr,null,UNAVAILABLE}
[junit4] 2> 1628803 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1628805 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest ### Adding last overseer designate
127.0.0.1:33222_solr
[junit4] 2> 1628806 INFO (qtp382860459-19954) [n:127.0.0.1:33222_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:33222_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1628810 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000003)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43707_solr
[junit4] 2> 1628819 INFO
(OverseerThreadFactory-6799-thread-1-processing-n:127.0.0.1:43707_solr)
[n:127.0.0.1:43707_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:33222_solr
[junit4] 2> 1628825 INFO (qtp382860459-19954) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:33222_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=19
[junit4] 2> 1628831 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:43707_solr
[junit4] 2> 1628832 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 1628834 INFO (qtp382860459-19957) [n:127.0.0.1:33222_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with
params
node=127.0.0.1:43707_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1628837 INFO
(OverseerCollectionConfigSetProcessor-72129576976449551-127.0.0.1:43707_solr-n_0000000003)
[n:127.0.0.1:43707_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> 1628837 INFO
(OverseerThreadFactory-6799-thread-2-processing-n:127.0.0.1:43707_solr)
[n:127.0.0.1:43707_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed
from 127.0.0.1:43707_solr
[junit4] 2> 1628840 INFO (Thread-3352) [ ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
72129576976449551-127.0.0.1:43707_solr-n_0000000003 overseer designates are
[127.0.0.1:46072_solr, 127.0.0.1:33222_solr]
[junit4] 2> 1628841 INFO (Thread-3352) [ ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449551-127.0.0.1:43707_solr-n_0000000003,
72129576976449544-127.0.0.1:40594_solr-n_0000000004,
72129576976449542-127.0.0.1:33222_solr-n_0000000005]
[junit4] 2> 1628841 INFO (Thread-3352) [ ]
o.a.s.c.OverseerNodePrioritizer asking node
72129576976449542-127.0.0.1:33222_solr-n_0000000005 to come join election at
head
[junit4] 2> 1628841 INFO (qtp382860459-19957) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43707_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 1628843 INFO (qtp382860459-19954) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449542-127.0.0.1:33222_solr-n_0000000005&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628843 INFO (Thread-3352) [ ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
72129576976449544-127.0.0.1:40594_solr-n_0000000004 to rejoin election
[junit4] 2> 1628848 INFO (qtp929832311-19966) [n:127.0.0.1:40594_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449544-127.0.0.1:40594_solr-n_0000000004&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628851 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000003)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449551-127.0.0.1:43707_solr-n_0000000003"}
127.0.0.1:43707_solr
[junit4] 2> 1628851 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000003)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43707_solr
[junit4] 2> 1628852 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449551-127.0.0.1:43707_solr-n_0000000003) am exiting, but I'm
still the leader
[junit4] 2> 1628854 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449551-127.0.0.1:43707_solr-n_0000000003) closing
[junit4] 2> 1628854 INFO (zkCallback-6743-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40594_solr
[junit4] 2> 1628854 WARN
(OverseerAutoScalingTriggerThread-72129576976449551-127.0.0.1:43707_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1628857 INFO (zkCallback-6743-thread-2)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000004) starting
[junit4] 2> 1628864 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:40594_solr
[junit4] 2> 1628874 INFO
(OverseerCollectionConfigSetProcessor-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[n:127.0.0.1:40594_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 72129576976449544-127.0.0.1:40594_solr-n_0000000004 overseer
designates are [127.0.0.1:46072_solr, 127.0.0.1:33222_solr]
[junit4] 2> 1628874 INFO
(OverseerCollectionConfigSetProcessor-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[n:127.0.0.1:40594_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449544-127.0.0.1:40594_solr-n_0000000004,
72129576976449542-127.0.0.1:33222_solr-n_0000000005,
72129576976449551-127.0.0.1:43707_solr-n_0000000006]
[junit4] 2> 1628877 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449544-127.0.0.1:40594_solr-n_0000000004"}
127.0.0.1:40594_solr
[junit4] 2> 1628878 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40594_solr
[junit4] 2> 1628882 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000004) am exiting, but I'm
still the leader
[junit4] 2> 1628883 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449544-127.0.0.1:40594_solr-n_0000000004) closing
[junit4] 2> 1628889 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33222_solr
[junit4] 2> 1628891 INFO (zkCallback-6736-thread-1)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000005) starting
[junit4] 2> 1628894 WARN
(OverseerAutoScalingTriggerThread-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[ ] o.a.s.c.a.OverseerTriggerThread Error removing old nodeAdded markers
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Object.wait(Object.java:502) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn.submitRequest(ClientCnxn.java:1409)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1533)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:329)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:329)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:67)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:77)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:272)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1628894 WARN
(OverseerAutoScalingTriggerThread-72129576976449544-127.0.0.1:40594_solr-n_0000000004)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed,
exiting.
[junit4] 2> 1628900 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000005)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:33222_solr
[junit4] 2> 1628944 INFO (qtp382860459-19954) [n:127.0.0.1:33222_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:43707_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1628947 INFO
(OverseerThreadFactory-6807-thread-1-processing-n:127.0.0.1:33222_solr)
[n:127.0.0.1:33222_solr ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to
127.0.0.1:43707_solr
[junit4] 2> 1628951 INFO (qtp382860459-19954) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43707_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=7
[junit4] 2> 1628955 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:33222_solr
[junit4] 2> 1628958 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000005)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449542-127.0.0.1:33222_solr-n_0000000005"}
127.0.0.1:33222_solr
[junit4] 2> 1628959 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000005)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33222_solr
[junit4] 2> 1628959 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000005) am exiting, but I'm
still the leader
[junit4] 2> 1628961 INFO (Thread-3353) [ ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
72129576976449542-127.0.0.1:33222_solr-n_0000000005 overseer designates are
[127.0.0.1:46072_solr, 127.0.0.1:33222_solr, 127.0.0.1:43707_solr]
[junit4] 2> 1628961 INFO (Thread-3353) [ ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449542-127.0.0.1:33222_solr-n_0000000005,
72129576976449551-127.0.0.1:43707_solr-n_0000000006,
72129576976449544-127.0.0.1:40594_solr-n_0000000007]
[junit4] 2> 1628961 INFO (Thread-3353) [ ]
o.a.s.c.OverseerNodePrioritizer asking node
72129576976449542-127.0.0.1:33222_solr-n_0000000005 to come join election at
head
[junit4] 2> 1628961 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449542-127.0.0.1:33222_solr-n_0000000005) closing
[junit4] 2> 1628962 WARN
(OverseerAutoScalingTriggerThread-72129576976449542-127.0.0.1:33222_solr-n_0000000005)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1628963 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43707_solr
[junit4] 2> 1628966 INFO (qtp382860459-19950) [n:127.0.0.1:33222_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449542-127.0.0.1:33222_solr-n_0000000005&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628966 INFO (zkCallback-6777-thread-1)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449551-127.0.0.1:43707_solr-n_0000000006) starting
[junit4] 2> 1628967 INFO (Thread-3353) [ ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
72129576976449551-127.0.0.1:43707_solr-n_0000000006 to rejoin election
[junit4] 2> 1628968 INFO (qtp1492291888-19944) [n:127.0.0.1:43707_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=72129576976449551-127.0.0.1:43707_solr-n_0000000006&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1628974 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43707_solr
[junit4] 2> 1628977 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449551-127.0.0.1:43707_solr-n_0000000006"}
127.0.0.1:43707_solr
[junit4] 2> 1628977 INFO
(OverseerStateUpdate-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[n:127.0.0.1:43707_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43707_solr
[junit4] 2> 1628978 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449551-127.0.0.1:43707_solr-n_0000000006) am exiting, but I'm
still the leader
[junit4] 2> 1628979 INFO (zkCallback-6743-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40594_solr
[junit4] 2> 1628979 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449551-127.0.0.1:43707_solr-n_0000000006) closing
[junit4] 2> 1628980 INFO (zkCallback-6743-thread-1)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000007) starting
[junit4] 2> 1628989 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:40594_solr
[junit4] 2> 1628991 INFO
(OverseerCollectionConfigSetProcessor-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[n:127.0.0.1:40594_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 72129576976449544-127.0.0.1:40594_solr-n_0000000007 overseer
designates are [127.0.0.1:46072_solr, 127.0.0.1:33222_solr,
127.0.0.1:43707_solr]
[junit4] 2> 1628991 INFO
(OverseerCollectionConfigSetProcessor-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[n:127.0.0.1:40594_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[72129576976449544-127.0.0.1:40594_solr-n_0000000007,
72129576976449542-127.0.0.1:33222_solr-n_0000000008]
[junit4] 2> 1628988 WARN
(OverseerAutoScalingTriggerThread-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[ ] o.a.s.c.a.OverseerTriggerThread Exception initializing trigger
.scheduled_maintenance, configuration ignored
[junit4] 2> org.apache.lucene.store.AlreadyClosedException:
ScheduledTriggers has been closed and cannot be used anymore
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.add(ScheduledTriggers.java:203)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:242)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1628993 WARN
(OverseerAutoScalingTriggerThread-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[ ] o.a.s.c.a.OverseerTriggerThread Exception initializing trigger
.auto_add_replicas, configuration ignored
[junit4] 2> org.apache.lucene.store.AlreadyClosedException:
ScheduledTriggers has been closed and cannot be used anymore
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.add(ScheduledTriggers.java:203)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:242)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1628994 WARN
(OverseerAutoScalingTriggerThread-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[ ] o.a.s.c.a.OverseerTriggerThread Error removing old nodeAdded markers
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Object.wait(Object.java:502) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn.submitRequest(ClientCnxn.java:1409)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1533)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:329)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:329)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:67)
~[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:77)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:272)
[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 1628995 WARN
(OverseerAutoScalingTriggerThread-72129576976449551-127.0.0.1:43707_solr-n_0000000006)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed,
exiting.
[junit4] 2> 1629000 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"72129576976449544-127.0.0.1:40594_solr-n_0000000007"}
127.0.0.1:40594_solr
[junit4] 2> 1629001 INFO
(OverseerStateUpdate-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[n:127.0.0.1:40594_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40594_solr
[junit4] 2> 1629002 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I
(id=72129576976449544-127.0.0.1:40594_solr-n_0000000007) am exiting, but I'm
still the leader
[junit4] 2> 1629003 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer
Overseer (id=72129576976449544-127.0.0.1:40594_solr-n_0000000007) closing
[junit4] 2> 1629004 WARN
(OverseerAutoScalingTriggerThread-72129576976449544-127.0.0.1:40594_solr-n_0000000007)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1629005 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33222_solr
[junit4] 2> 1629006 INFO (zkCallback-6736-thread-1)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000008) starting
[junit4] 2> 1629013 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000008)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:33222_solr
[junit4] 2> 1643962 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[FC64A1B454A42F26]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testOverseerRole
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerRolesTest
-Dtests.method=testOverseerRole -Dtests.seed=FC64A1B454A42F26
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-NZ
-Dtests.timezone=America/Bahia_Banderas -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 15.4s J1 | OverseerRolesTest.testOverseerRole <<<
[junit4] > Throwable #1: java.lang.AssertionError: Timed out waiting for
overseer state change
[junit4] > at
__randomizedtesting.SeedInfo.seed([FC64A1B454A42F26:1DAF5C206F1719F7]:0)
[junit4] > at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:63)
[junit4] > at
org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:143)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1643968 INFO (jetty-closer-6720-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2338c131{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1643968 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1441910721
[junit4] 2> 1643969 INFO (jetty-closer-6720-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@75268049{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1643969 INFO (jetty-closer-6720-thread-4) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3a46ffac{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1643970 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1643970 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@40359883: rootName =
solr_33222, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@18e28b62
[junit4] 2> 1643975 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1229558725
[junit4] 2> 1643976 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1643976 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d9b3690: rootName =
solr_40594, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@33047b0f
[junit4] 2> 1643978 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1643978 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@27804605: rootName =
solr_33222, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1c55b8df
[junit4] 2> 1643981 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1789662275
[junit4] 2> 1643982 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1643982 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@308108cc: rootName =
solr_43707, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@6bfee833
[junit4] 2> 1643991 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1643991 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@598d0f37: rootName =
solr_33222, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2cfd0592
[junit4] 2> 1643994 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:33222_solr
[junit4] 2> 1643996 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1643996 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643996 INFO (zkCallback-6743-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643996 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643996 INFO (zkCallback-6736-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643996 INFO (zkCallback-6757-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643996 INFO (zkCallback-6784-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1643998 INFO (jetty-closer-6720-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72129576976449542-127.0.0.1:33222_solr-n_0000000008) closing
[junit4] 2> 1643998 INFO
(OverseerStateUpdate-72129576976449542-127.0.0.1:33222_solr-n_0000000008)
[n:127.0.0.1:33222_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33222_solr
[junit4] 2> 1643999 WARN
(OverseerAutoScalingTriggerThread-72129576976449542-127.0.0.1:33222_solr-n_0000000008)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1644002 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1644002 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6df691e7: rootName =
solr_40594, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1c55b8df
[junit4] 2> 1644006 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1644006 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@48c8eff5: rootName =
solr_43707, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@1c55b8df
[junit4] 2> 1644006 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1644006 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2d29ad7d: rootName =
solr_40594, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2cfd0592
[junit4] 2> 1644008 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43707_solr
[junit4] 2> 1644008 INFO (jetty-closer-6720-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2a5a2ba3{/solr,null,UNAVAILABLE}
[junit4] 2> 1644009 INFO (jetty-closer-6720-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1644011 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:40594_solr
[junit4] 2> 1644012 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1644012 INFO (jetty-closer-6720-thread-2) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1644012 INFO (zkCallback-6750-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1644012 INFO (zkCallback-6784-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1644012 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1644012 INFO (zkCallback-6743-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1644014 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1644014 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@54482a62: rootName =
solr_43707, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2cfd0592
[junit4] 2> 1644015 INFO (jetty-closer-6720-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@549cf41e{/solr,null,UNAVAILABLE}
[junit4] 2> 1644015 INFO (jetty-closer-6720-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1644016 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:43707_solr
[junit4] 2> 1644017 INFO (jetty-closer-6720-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1644031 INFO (zkCallback-6784-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1644031 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
0x1004177d57b0010, likely client has closed socket
[junit4] 2> 1644032 INFO (zkCallback-6777-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1644033 INFO (jetty-closer-6720-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@33dfa3a{/solr,null,UNAVAILABLE}
[junit4] 2> 1644033 INFO (jetty-closer-6720-thread-4) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1644034 ERROR
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1644035 INFO
(SUITE-OverseerRolesTest-seed#[FC64A1B454A42F26]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45639 45639
[junit4] 2> 1644116 INFO (Thread-3311) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:45639 45639
[junit4] 2> 1644117 WARN (Thread-3311) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/aliases.json
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 9 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2> 9 /solr/overseer/queue-work
[junit4] 2> 8 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 7 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerRolesTest_FC64A1B454A42F26-001
[junit4] 2> Oct 19, 2018 3:40:02 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1064, maxMBSortInHeap=5.687877677786592,
sim=RandomSimilarity(queryNorm=true): {}, locale=en-NZ,
timezone=America/Bahia_Banderas
[junit4] 2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation
1.8.0_172 (64-bit)/cpus=4,threads=1,free=208684368,total=518520832
[junit4] 2> NOTE: All tests run in this JVM:
[AssignBackwardCompatibilityTest, TestCloudPhrasesIdentificationComponent,
TestXmlQParser, BlockDirectoryTest, CurrencyRangeFacetCloudTest,
HdfsThreadLeakTest, TestDistributedSearch, ComputePlanActionTest,
CollectionStateFormat2Test, LeaderElectionTest, ClusterStateTest,
VersionInfoTest, MoveReplicaHDFSFailoverTest, TestConfigOverlay,
ActionThrottleTest, TestExactStatsCache, PeerSyncWithBufferUpdatesTest,
SolrRrdBackendFactoryTest, BasicAuthStandaloneTest, TestStressLiveNodes,
TestSimNodeLostTrigger, TestOverriddenPrefixQueryForCustomFieldType,
MetricTriggerTest, TestSimGenericDistributedQueue, MBeansHandlerTest,
HdfsWriteToMultipleCollectionsTest, FileBasedSpellCheckerTest,
TestReloadDeadlock, SortByFunctionTest, TestCoreAdminApis,
SharedFSAutoReplicaFailoverTest, TestCollectionAPI,
TestTrackingShardHandlerFactory, TestSuggestSpellingConverter, EnumFieldTest,
TestSolrCloudWithSecureImpersonation, TestNumericTokenStream,
TestHashPartitioner, DistributedFacetPivotLongTailTest,
ReplicaListTransformerTest, TestApiFramework, TestTolerantSearch,
MinimalSchemaTest, DistributedFacetPivotWhiteBoxTest, SaslZkACLProviderTest,
IndexSchemaTest, TestPerFieldSimilarityWithDefaultOverride,
TestGeoJSONResponseWriter, TestLocalFSCloudBackupRestore, TestComponentsName,
UUIDFieldTest, TestJsonFacets, TestZkAclsWithHadoopAuth,
DistribJoinFromCollectionTest, NotRequiredUniqueKeyTest, TestSolrCoreSnapshots,
InfoHandlerTest, BasicFunctionalityTest, SparseHLLTest, SynonymTokenizerTest,
TestCollapseQParserPlugin, RangeFacetCloudTest, TestConfigSets, TestLRUCache,
TestUnInvertedFieldException, PeerSyncWithLeaderTest, TaggerTest,
MetricsConfigTest, TestInfoStreamLogging, HdfsLockFactoryTest,
TestPullReplicaErrorHandling, BJQParserTest, ClassificationUpdateProcessorTest,
TestCopyFieldCollectionResource, TestBM25SimilarityFactory,
SystemLogListenerTest, HighlighterTest, TestConfigSetsAPI,
TestLegacyFieldCache, TestLuceneIndexBackCompat, TestAuthenticationFramework,
SignatureUpdateProcessorFactoryTest, TestRandomFaceting,
TestSimpleQParserPlugin, CdcrUpdateLogTest, RuleEngineTest,
MultiSolrCloudTestCaseTest, TestSortableTextField, TestCloudRecovery,
TestFieldCollectionResource, CdcrVersionReplicationTest, AtomicUpdatesTest,
UnloadDistributedZkTest, TestFileDictionaryLookup,
UUIDUpdateProcessorFallbackTest, ShowFileRequestHandlerTest,
PropertiesRequestHandlerTest, MergeStrategyTest, BooleanFieldTest,
TestEmbeddedSolrServerSchemaAPI, SolrMetricReporterTest,
FastVectorHighlighterTest, TestPointFields, ProtectedTermFilterFactoryTest,
OverseerTaskQueueTest, SortSpecParsingTest, DistributedExpandComponentTest,
HdfsCollectionsAPIDistributedZkTest, TestBadConfig,
TestPKIAuthenticationPlugin, TestSkipOverseerOperations, TestFieldTypeResource,
TestDistributedMap, BlobRepositoryCloudTest,
TestManagedSynonymGraphFilterFactory, TestMultiValuedNumericRangeQuery,
HdfsChaosMonkeySafeLeaderTest, TestSolrXml, ExitableDirectoryReaderTest,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, RestoreTriggerStateTest,
TestSolrJ, TestNestedDocsSort, DeleteStatusTest, DisMaxRequestHandlerTest,
SolrIndexConfigTest, TestReqParamsAPI, SolrGraphiteReporterTest,
DocValuesNotIndexedTest, TestLeaderInitiatedRecoveryThread,
RecoveryAfterSoftCommitTest, TestElisionMultitermQuery, InfixSuggestersTest,
TestAnalyzeInfixSuggestions, SchemaVersionSpecificBehaviorTest,
TestOnReconnectListenerSupport, SampleTest, SolrInfoBeanTest,
SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey,
TestDistributedGrouping, TestGroupingSearch, TestHighlightDedupGrouping,
TestJoin, TestLuceneMatchVersion, TestReversedWildcardFilterFactory,
TestWordDelimiterFilterFactory, BasicDistributedZk2Test,
BasicDistributedZkTest, BasicZkTest, ChaosMonkeyNothingIsSafeTest,
ChaosMonkeyNothingIsSafeWithPullReplicasTest, ChaosMonkeySafeLeaderTest,
ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, ConnectionManagerTest,
CreateRoutedAliasTest, DeleteNodeTest, FullSolrCloudDistribCmdsTest,
HttpPartitionOnCommitTest, HttpPartitionTest, LIROnShardRestartTest,
LIRRollingUpdatesTest, MetricsHistoryIntegrationTest, NodeMutatorTest,
OutOfBoxZkACLAndCredentialsProvidersTest,
OverriddenZkACLAndCredentialsProvidersTest,
OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest,
OverseerRolesTest]
[junit4] Completed [430/834 (1!)] on J1 in 17.70s, 1 test, 1 failure <<<
FAILURES!
[...truncated 50351 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1:
invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid
part, must have at least one section (e.g., chapter, appendix, etc.)
[java] Processed 2434 links (1986 relative) to 3182 anchors in 246 files
[echo] Validated Links & Anchors via:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for malformed docs...
jar-checksums:
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-Tests-7.x/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-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its leng
[...truncated too long message...]
eck:
[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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/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-Tests-7.x/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1994392082
[copy] Copying 241 files to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1994392082
[delete] Deleting directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/null1994392082
check-working-copy:
[ivy:cachepath] :: resolving dependencies ::
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in
public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 36ms :: artifacts dl 2ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for
further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 104 minutes 9 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
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:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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:2920)
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:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
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:1819)
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]
