Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/91/
2 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([58BD45E96E63CC7E:B976B87D55D0FAAF]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:62)
at
org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:140)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.TestPullReplica.testKillLeader
Error Message:
Replica state not updated in cluster state null Live Nodes:
[127.0.0.1:35682_solr, 127.0.0.1:60807_solr] Last available state:
DocCollection(pull_replica_test_kill_leader//collections/pull_replica_test_kill_leader/state.json/6)={
"pullReplicas":"1", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node1":{
"core":"pull_replica_test_kill_leader_shard1_replica_n1",
"base_url":"https://127.0.0.1:60807/solr";,
"node_name":"127.0.0.1:60807_solr", "state":"down",
"type":"NRT", "leader":"true"}, "core_node2":{
"core":"pull_replica_test_kill_leader_shard1_replica_p1",
"base_url":"https://127.0.0.1:35682/solr";,
"node_name":"127.0.0.1:35682_solr", "state":"active",
"type":"PULL"}}}}, "router":{"name":"compositeId"},
"maxShardsPerNode":"100", "autoAddReplicas":"false", "nrtReplicas":"1",
"tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Replica state not updated in cluster state
null
Live Nodes: [127.0.0.1:35682_solr, 127.0.0.1:60807_solr]
Last available state:
DocCollection(pull_replica_test_kill_leader//collections/pull_replica_test_kill_leader/state.json/6)={
"pullReplicas":"1",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node1":{
"core":"pull_replica_test_kill_leader_shard1_replica_n1",
"base_url":"https://127.0.0.1:60807/solr";,
"node_name":"127.0.0.1:60807_solr",
"state":"down",
"type":"NRT",
"leader":"true"},
"core_node2":{
"core":"pull_replica_test_kill_leader_shard1_replica_p1",
"base_url":"https://127.0.0.1:35682/solr";,
"node_name":"127.0.0.1:35682_solr",
"state":"active",
"type":"PULL"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"100",
"autoAddReplicas":"false",
"nrtReplicas":"1",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([58BD45E96E63CC7E:11ABB15D0CD85828]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at
org.apache.solr.cloud.TestPullReplica.doTestNoLeader(TestPullReplica.java:401)
at
org.apache.solr.cloud.TestPullReplica.testKillLeader(TestPullReplica.java:290)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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 11256 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> 588808 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/init-core-data-001
[junit4] 2> 588808 WARN
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 588809 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 588810 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-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> 588810 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/tempDir-001
[junit4] 2> 588811 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 588815 INFO (Thread-548) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 588815 INFO (Thread-548) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 588816 ERROR (Thread-548) [ ] 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> 588915 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:37902
[junit4] 2> 588988 INFO (jetty-launcher-429-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 588995 INFO (jetty-launcher-429-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 589001 INFO (jetty-launcher-429-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 589007 INFO (jetty-launcher-429-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 589027 INFO (jetty-launcher-429-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@72b88eaf{/solr,null,AVAILABLE}
[junit4] 2> 589028 INFO (jetty-launcher-429-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7b2246bc{HTTP/1.1,[http/1.1]}{127.0.0.1:53540}
[junit4] 2> 589028 INFO (jetty-launcher-429-thread-1) [ ]
o.e.j.s.Server Started @591111ms
[junit4] 2> 589029 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=53540}
[junit4] 2> 589029 ERROR (jetty-launcher-429-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 589029 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 589029 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 589029 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 589029 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:35:14.301Z
[junit4] 2> 589046 INFO (jetty-launcher-429-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@19463cf8{/solr,null,AVAILABLE}
[junit4] 2> 589046 INFO (jetty-launcher-429-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5435323a{HTTP/1.1,[http/1.1]}{127.0.0.1:50153}
[junit4] 2> 589046 INFO (jetty-launcher-429-thread-3) [ ]
o.e.j.s.Server Started @591128ms
[junit4] 2> 589046 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=50153}
[junit4] 2> 589047 ERROR (jetty-launcher-429-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 589047 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 589047 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 589047 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 589047 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:35:14.319Z
[junit4] 2> 589050 INFO (jetty-launcher-429-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@31e57db0{/solr,null,AVAILABLE}
[junit4] 2> 589056 INFO (jetty-launcher-429-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5c6c6edc{HTTP/1.1,[http/1.1]}{127.0.0.1:49618}
[junit4] 2> 589056 INFO (jetty-launcher-429-thread-4) [ ]
o.e.j.s.Server Started @591138ms
[junit4] 2> 589056 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49618}
[junit4] 2> 589057 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 589062 ERROR (jetty-launcher-429-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 589062 INFO (jetty-launcher-429-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4c6ab237{/solr,null,AVAILABLE}
[junit4] 2> 589066 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 589066 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 589066 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 589066 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:35:14.338Z
[junit4] 2> 589067 INFO (jetty-launcher-429-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4b7fb413{HTTP/1.1,[http/1.1]}{127.0.0.1:55912}
[junit4] 2> 589067 INFO (jetty-launcher-429-thread-2) [ ]
o.e.j.s.Server Started @591150ms
[junit4] 2> 589067 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55912}
[junit4] 2> 589068 ERROR (jetty-launcher-429-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 589068 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 589068 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 589068 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 589068 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:35:14.340Z
[junit4] 2> 589074 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 589080 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 589085 INFO (jetty-launcher-429-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37902/solr
[junit4] 2> 589087 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 589094 INFO (jetty-launcher-429-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37902/solr
[junit4] 2> 589104 INFO (jetty-launcher-429-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37902/solr
[junit4] 2> 589115 INFO (jetty-launcher-429-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37902/solr
[junit4] 2> 589170 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 589170 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 589194 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49618_solr
[junit4] 2> 589195 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53540_solr
[junit4] 2> 589195 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000000) starting
[junit4] 2> 589205 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 589207 INFO
(zkCallback-451-thread-1-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 589239 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 589240 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 589241 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50153_solr
[junit4] 2> 589242 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 589242 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 589242 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 589244 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 589245 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55912_solr
[junit4] 2> 589245 INFO
(zkCallback-451-thread-1-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 589245 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 589247 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 589255 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000000)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 589259 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49618_solr
[junit4] 2> 589260 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 589260 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 589319 INFO
(zkCallback-452-thread-1-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 589365 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 589383 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_50153.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589385 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_50153.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589385 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_50153.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589386 INFO (jetty-launcher-429-thread-3)
[n:127.0.0.1:50153_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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/tempDir-001/node3/.
[junit4] 2> 589400 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_49618.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589402 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_49618.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589403 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_49618.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589403 INFO (jetty-launcher-429-thread-4)
[n:127.0.0.1:49618_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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/tempDir-001/node4/.
[junit4] 2> 589442 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55912.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589444 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53540.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589448 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55912.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589449 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55912.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589449 INFO (jetty-launcher-429-thread-2)
[n:127.0.0.1:55912_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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/tempDir-001/node2/.
[junit4] 2> 589455 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53540.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589455 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_53540.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1a1e3198
[junit4] 2> 589456 INFO (jetty-launcher-429-thread-1)
[n:127.0.0.1:53540_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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001/tempDir-001/node1/.
[junit4] 2> 589604 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 589604 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37902/solr ready
[junit4] 2> 589634 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
[junit4] 2> 589634 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:49618_solr
[junit4] 2> 589634 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 589635 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:50153_solr
[junit4] 2> 589653 INFO (qtp183705559-3954) [n:127.0.0.1:49618_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:50153_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589671 INFO
(OverseerThreadFactory-1513-thread-1-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to
127.0.0.1:50153_solr
[junit4] 2> 589684 INFO (Thread-585) [n:127.0.0.1:49618_solr ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
98356357633671179-127.0.0.1:49618_solr-n_0000000000 overseer designates are
[127.0.0.1:50153_solr]
[junit4] 2> 589684 INFO (qtp183705559-3954) [n:127.0.0.1:49618_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:50153_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=31
[junit4] 2> 589685 INFO (Thread-585) [n:127.0.0.1:49618_solr ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671179-127.0.0.1:49618_solr-n_0000000000,
98356357633671177-127.0.0.1:53540_solr-n_0000000001,
98356357633671175-127.0.0.1:50153_solr-n_0000000002,
98356357633671180-127.0.0.1:55912_solr-n_0000000003]
[junit4] 2> 589685 INFO (Thread-585) [n:127.0.0.1:49618_solr ]
o.a.s.c.OverseerNodePrioritizer asking node
98356357633671175-127.0.0.1:50153_solr-n_0000000002 to come join election at
head
[junit4] 2> 589709 INFO (qtp626402728-3935) [n:127.0.0.1:50153_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671175-127.0.0.1:50153_solr-n_0000000002&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 589709 INFO (Thread-585) [n:127.0.0.1:49618_solr ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
98356357633671177-127.0.0.1:53540_solr-n_0000000001 to rejoin election
[junit4] 2> 589728 INFO (qtp1097452269-3938) [n:127.0.0.1:53540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671177-127.0.0.1:53540_solr-n_0000000001&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 589729 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000000)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671179-127.0.0.1:49618_solr-n_0000000000"}
127.0.0.1:49618_solr
[junit4] 2> 589730 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000000)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:49618_solr
[junit4] 2> 589740 WARN (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 589745 INFO (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000000) closing
[junit4] 2> 589746 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:53540_solr
[junit4] 2> 589746 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000001) starting
[junit4] 2> 589762 INFO
(OverseerCollectionConfigSetProcessor-98356357633671177-127.0.0.1:53540_solr-n_0000000001)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 98356357633671177-127.0.0.1:53540_solr-n_0000000001 overseer
designates are [127.0.0.1:50153_solr]
[junit4] 2> 589768 INFO
(OverseerCollectionConfigSetProcessor-98356357633671177-127.0.0.1:53540_solr-n_0000000001)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671177-127.0.0.1:53540_solr-n_0000000001,
98356357633671175-127.0.0.1:50153_solr-n_0000000002,
98356357633671180-127.0.0.1:55912_solr-n_0000000003,
98356357633671179-127.0.0.1:49618_solr-n_0000000004]
[junit4] 2> 589769 INFO
(OverseerStateUpdate-98356357633671177-127.0.0.1:53540_solr-n_0000000001)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671177-127.0.0.1:53540_solr-n_0000000001"}
127.0.0.1:53540_solr
[junit4] 2> 589769 INFO
(OverseerStateUpdate-98356357633671177-127.0.0.1:53540_solr-n_0000000001)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:53540_solr
[junit4] 2> 589786 WARN (OverseerExitThread) [n:127.0.0.1:53540_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 589787 INFO (OverseerExitThread) [n:127.0.0.1:53540_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000001) closing
[junit4] 2> 589793 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:50153_solr
[junit4] 2> 589794 INFO
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671175-127.0.0.1:50153_solr-n_0000000002) starting
[junit4] 2> 589886 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest ### Adding another overseer designate
127.0.0.1:55912_solr
[junit4] 2> 589886 INFO (qtp183705559-3955) [n:127.0.0.1:49618_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:55912_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 589903 INFO
(OverseerThreadFactory-1525-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to
127.0.0.1:55912_solr
[junit4] 2> 589912 INFO (qtp183705559-3955) [n:127.0.0.1:49618_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:55912_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=25
[junit4] 2> 589913 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:50153_solr
[junit4] 2> 589913 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 589913 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 50153
[junit4] 2> 589913 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=845450206
[junit4] 2> 589913 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 589915 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 589916 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 589917 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 589917 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 589917 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.Overseer Overseer
(id=98356357633671175-127.0.0.1:50153_solr-n_0000000002) closing
[junit4] 2> 589917 INFO
(OverseerStateUpdate-98356357633671175-127.0.0.1:50153_solr-n_0000000002)
[n:127.0.0.1:50153_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:50153_solr
[junit4] 2> 589935 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 589935 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 589935 INFO
(zkCallback-452-thread-1-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55912_solr
[junit4] 2> 589935 INFO
(zkCallback-452-thread-1-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671180-127.0.0.1:55912_solr-n_0000000003) starting
[junit4] 2> 589947 INFO (zkCallback-459-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 589948 INFO
(zkCallback-452-thread-2-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 591435 WARN
(zkCallback-445-thread-1-processing-n:127.0.0.1:50153_solr)
[n:127.0.0.1:50153_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 591438 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@5435323a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 591445 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@19463cf8{/solr,null,UNAVAILABLE}
[junit4] 2> 591451 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest ### Adding last overseer designate
127.0.0.1:53540_solr
[junit4] 2> 591453 INFO (qtp1097452269-3940) [n:127.0.0.1:53540_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:53540_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 591455 INFO
(OverseerThreadFactory-1528-thread-1-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to
127.0.0.1:53540_solr
[junit4] 2> 591467 INFO (qtp1097452269-3940) [n:127.0.0.1:53540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:53540_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=14
[junit4] 2> 591467 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:55912_solr
[junit4] 2> 591468 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 591469 INFO (qtp1097452269-3941) [n:127.0.0.1:53540_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params
node=127.0.0.1:55912_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 591471 INFO
(OverseerCollectionConfigSetProcessor-98356357633671180-127.0.0.1:55912_solr-n_0000000003)
[n:127.0.0.1:55912_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> 591471 INFO
(OverseerThreadFactory-1528-thread-2-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.OverseerRoleCmd Overseer role removed from
127.0.0.1:55912_solr
[junit4] 2> 591480 INFO (qtp1097452269-3941) [n:127.0.0.1:53540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:55912_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2}
status=0 QTime=10
[junit4] 2> 591480 INFO (Thread-589) [n:127.0.0.1:55912_solr ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
98356357633671180-127.0.0.1:55912_solr-n_0000000003 overseer designates are
[127.0.0.1:50153_solr, 127.0.0.1:53540_solr]
[junit4] 2> 591484 INFO (Thread-589) [n:127.0.0.1:55912_solr ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671180-127.0.0.1:55912_solr-n_0000000003,
98356357633671179-127.0.0.1:49618_solr-n_0000000004,
98356357633671177-127.0.0.1:53540_solr-n_0000000005]
[junit4] 2> 591484 INFO (Thread-589) [n:127.0.0.1:55912_solr ]
o.a.s.c.OverseerNodePrioritizer asking node
98356357633671177-127.0.0.1:53540_solr-n_0000000005 to come join election at
head
[junit4] 2> 591487 INFO (qtp1097452269-3942) [n:127.0.0.1:53540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671177-127.0.0.1:53540_solr-n_0000000005&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 591488 INFO (Thread-589) [n:127.0.0.1:55912_solr ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
98356357633671179-127.0.0.1:49618_solr-n_0000000004 to rejoin election
[junit4] 2> 591508 INFO (qtp183705559-3957) [n:127.0.0.1:49618_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671179-127.0.0.1:49618_solr-n_0000000004&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 591510 INFO
(OverseerStateUpdate-98356357633671180-127.0.0.1:55912_solr-n_0000000003)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671180-127.0.0.1:55912_solr-n_0000000003"}
127.0.0.1:55912_solr
[junit4] 2> 591510 INFO
(OverseerStateUpdate-98356357633671180-127.0.0.1:55912_solr-n_0000000003)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:55912_solr
[junit4] 2> 591511 WARN (OverseerExitThread) [n:127.0.0.1:55912_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 591512 INFO (OverseerExitThread) [n:127.0.0.1:55912_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671180-127.0.0.1:55912_solr-n_0000000003) closing
[junit4] 2> 591513 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49618_solr
[junit4] 2> 591513 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000004) starting
[junit4] 2> 591536 INFO
(OverseerCollectionConfigSetProcessor-98356357633671179-127.0.0.1:49618_solr-n_0000000004)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 98356357633671179-127.0.0.1:49618_solr-n_0000000004 overseer
designates are [127.0.0.1:50153_solr, 127.0.0.1:53540_solr]
[junit4] 2> 591536 INFO
(OverseerCollectionConfigSetProcessor-98356357633671179-127.0.0.1:49618_solr-n_0000000004)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671179-127.0.0.1:49618_solr-n_0000000004,
98356357633671177-127.0.0.1:53540_solr-n_0000000005,
98356357633671180-127.0.0.1:55912_solr-n_0000000006]
[junit4] 2> 591538 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000004)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671179-127.0.0.1:49618_solr-n_0000000004"}
127.0.0.1:49618_solr
[junit4] 2> 591539 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000004)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:49618_solr
[junit4] 2> 591544 WARN (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 591545 INFO (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000004) closing
[junit4] 2> 591545 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:53540_solr
[junit4] 2> 591546 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000005) starting
[junit4] 2> 591581 INFO (qtp1097452269-3938) [n:127.0.0.1:53540_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params
node=127.0.0.1:55912_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 591592 INFO
(OverseerThreadFactory-1532-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to
127.0.0.1:55912_solr
[junit4] 2> 591596 INFO (qtp1097452269-3938) [n:127.0.0.1:53540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:55912_solr&role=overseer&action=ADDROLE&wt=javabin&version=2}
status=0 QTime=14
[junit4] 2> 591596 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:53540_solr
[junit4] 2> 591599 INFO
(OverseerStateUpdate-98356357633671177-127.0.0.1:53540_solr-n_0000000005)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671177-127.0.0.1:53540_solr-n_0000000005"}
127.0.0.1:53540_solr
[junit4] 2> 591600 INFO
(OverseerStateUpdate-98356357633671177-127.0.0.1:53540_solr-n_0000000005)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:53540_solr
[junit4] 2> 591611 WARN (OverseerExitThread) [n:127.0.0.1:53540_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 591612 INFO (OverseerExitThread) [n:127.0.0.1:53540_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000005) closing
[junit4] 2> 591612 INFO (Thread-590) [n:127.0.0.1:53540_solr ]
o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at
98356357633671177-127.0.0.1:53540_solr-n_0000000005 overseer designates are
[127.0.0.1:50153_solr, 127.0.0.1:53540_solr, 127.0.0.1:55912_solr]
[junit4] 2> 591613 INFO
(zkCallback-452-thread-2-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55912_solr
[junit4] 2> 591614 INFO (Thread-590) [n:127.0.0.1:53540_solr ]
o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671180-127.0.0.1:55912_solr-n_0000000006,
98356357633671179-127.0.0.1:49618_solr-n_0000000007,
98356357633671177-127.0.0.1:53540_solr-n_0000000008]
[junit4] 2> 591614 INFO
(zkCallback-452-thread-2-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671180-127.0.0.1:55912_solr-n_0000000006) starting
[junit4] 2> 591614 INFO (Thread-590) [n:127.0.0.1:53540_solr ]
o.a.s.c.OverseerNodePrioritizer asking node
98356357633671180-127.0.0.1:55912_solr-n_0000000006 to come join election at
head
[junit4] 2> 591634 INFO (qtp502264963-3963) [n:127.0.0.1:55912_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671180-127.0.0.1:55912_solr-n_0000000006&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 591634 INFO (Thread-590) [n:127.0.0.1:53540_solr ]
o.a.s.c.OverseerNodePrioritizer asking the old first in line
98356357633671179-127.0.0.1:49618_solr-n_0000000007 to rejoin election
[junit4] 2> 591635 INFO (qtp183705559-3957) [n:127.0.0.1:49618_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98356357633671179-127.0.0.1:49618_solr-n_0000000007&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 591636 INFO
(OverseerStateUpdate-98356357633671180-127.0.0.1:55912_solr-n_0000000006)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671180-127.0.0.1:55912_solr-n_0000000006"}
127.0.0.1:55912_solr
[junit4] 2> 591636 INFO
(OverseerStateUpdate-98356357633671180-127.0.0.1:55912_solr-n_0000000006)
[n:127.0.0.1:55912_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:55912_solr
[junit4] 2> 591637 WARN (OverseerExitThread) [n:127.0.0.1:55912_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 591637 INFO (OverseerExitThread) [n:127.0.0.1:55912_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671180-127.0.0.1:55912_solr-n_0000000006) closing
[junit4] 2> 591638 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49618_solr
[junit4] 2> 591638 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000007) starting
[junit4] 2> 591643 INFO
(OverseerCollectionConfigSetProcessor-98356357633671179-127.0.0.1:49618_solr-n_0000000007)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing
overseer nodes at 98356357633671179-127.0.0.1:49618_solr-n_0000000007 overseer
designates are [127.0.0.1:50153_solr, 127.0.0.1:53540_solr,
127.0.0.1:55912_solr]
[junit4] 2> 591644 INFO
(OverseerCollectionConfigSetProcessor-98356357633671179-127.0.0.1:49618_solr-n_0000000007)
[n:127.0.0.1:49618_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes
[98356357633671179-127.0.0.1:49618_solr-n_0000000007,
98356357633671177-127.0.0.1:53540_solr-n_0000000008,
98356357633671180-127.0.0.1:55912_solr-n_0000000009]
[junit4] 2> 591645 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000007)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98356357633671179-127.0.0.1:49618_solr-n_0000000007"}
127.0.0.1:49618_solr
[junit4] 2> 591645 INFO
(OverseerStateUpdate-98356357633671179-127.0.0.1:49618_solr-n_0000000007)
[n:127.0.0.1:49618_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:49618_solr
[junit4] 2> 591647 WARN (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 591648 INFO (OverseerExitThread) [n:127.0.0.1:49618_solr ]
o.a.s.c.Overseer Overseer
(id=98356357633671179-127.0.0.1:49618_solr-n_0000000007) closing
[junit4] 2> 591648 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:53540_solr
[junit4] 2> 591648 INFO
(zkCallback-449-thread-1-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000008) starting
[junit4] 2> 606604 INFO
(TEST-OverseerRolesTest.testOverseerRole-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testOverseerRole
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerRolesTest
-Dtests.method=testOverseerRole -Dtests.seed=58BD45E96E63CC7E
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=it
-Dtests.timezone=Asia/Tashkent -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 17.0s J2 | OverseerRolesTest.testOverseerRole <<<
[junit4] > Throwable #1: java.lang.AssertionError: Timed out waiting for
overseer state change
[junit4] > at
__randomizedtesting.SeedInfo.seed([58BD45E96E63CC7E:B976B87D55D0FAAF]:0)
[junit4] > at
org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:62)
[junit4] > at
org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:140)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 606639 INFO (jetty-closer-430-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@5c6c6edc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 606641 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1680838997
[junit4] 2> 606643 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 606644 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 606645 INFO (jetty-closer-430-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@7b2246bc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 606645 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2037078704
[junit4] 2> 606651 INFO (jetty-closer-430-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@4b7fb413{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 606652 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 606654 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=915342134
[junit4] 2> 606654 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 606654 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 606655 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 606656 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 606657 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 606658 INFO (jetty-closer-430-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 606661 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 606663 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 606664 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 606664 INFO (jetty-closer-430-thread-3) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 606665 INFO
(zkCallback-451-thread-2-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 606665 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15d6e960e28000b, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 606666 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 606666 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 606675 INFO (jetty-closer-430-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=98356357633671177-127.0.0.1:53540_solr-n_0000000008) closing
[junit4] 2> 606675 INFO
(OverseerStateUpdate-98356357633671177-127.0.0.1:53540_solr-n_0000000008)
[n:127.0.0.1:53540_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:53540_solr
[junit4] 2> 606676 INFO
(zkCallback-449-thread-2-processing-n:127.0.0.1:53540_solr)
[n:127.0.0.1:53540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (1)
[junit4] 2> 606716 INFO (jetty-closer-430-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@72b88eaf{/solr,null,UNAVAILABLE}
[junit4] 2> 608165 WARN
(zkCallback-452-thread-2-processing-n:127.0.0.1:55912_solr)
[n:127.0.0.1:55912_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 608165 INFO (jetty-closer-430-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4c6ab237{/solr,null,UNAVAILABLE}
[junit4] 2> 608165 WARN
(zkCallback-451-thread-1-processing-n:127.0.0.1:49618_solr)
[n:127.0.0.1:49618_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 608166 INFO (jetty-closer-430-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@31e57db0{/solr,null,UNAVAILABLE}
[junit4] 2> 608169 ERROR
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-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> 608169 INFO
(SUITE-OverseerRolesTest-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37902 37902
[junit4] 2> 608273 INFO (Thread-548) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:37902 37902
[junit4] 2> 608275 WARN (Thread-548) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2
/solr/overseer_elect/election/98356357633671177-127.0.0.1:53540_solr-n_0000000008
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/overseer/queue-work
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[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/J2/temp/solr.cloud.OverseerRolesTest_58BD45E96E63CC7E-001
[junit4] 2> Jul 23, 2017 8:35:33 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1083, maxMBSortInHeap=5.906273982989213,
sim=RandomSimilarity(queryNorm=false): {}, locale=it, timezone=Asia/Tashkent
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=106670800,total=528482304
[junit4] 2> NOTE: All tests run in this JVM:
[DirectUpdateHandlerOptimizeTest, TestNumericTokenStream, TestJsonRequest,
TestInPlaceUpdatesDistrib, DataDrivenBlockJoinTest, DeleteInactiveReplicaTest,
TestZkChroot, TestLMJelinekMercerSimilarityFactory, TestSchemaVersionResource,
CollectionsAPISolrJTest, TestAnalyzeInfixSuggestions, TestReloadAndDeleteDocs,
DateMathParserTest, TestLeaderElectionZkExpiry, TestDocBasedVersionConstraints,
ReturnFieldsTest, TestCollationFieldDocValues, ShowFileRequestHandlerTest,
ShardSplitTest, TestUtils, TestSolrDeletionPolicy2,
DistributedFacetPivotLongTailTest, OverseerRolesTest]
[junit4] Completed [149/727 (1!)] on J2 in 20.09s, 1 test, 1 failure <<<
FAILURES!
[...truncated 1344 lines...]
[junit4] Suite: org.apache.solr.cloud.TestPullReplica
[junit4] 2> 1808883 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/init-core-data-001
[junit4] 2> 1808884 WARN
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24
[junit4] 2> 1808884 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1808885 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1808885 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001
[junit4] 2> 1808886 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1808886 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1808886 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1808892 ERROR (Thread-3725) [ ] 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> 1808986 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36008
[junit4] 2> 1809000 INFO (jetty-launcher-2671-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1809001 INFO (jetty-launcher-2671-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1809002 INFO (jetty-launcher-2671-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@437d6281{/solr,null,AVAILABLE}
[junit4] 2> 1809002 INFO (jetty-launcher-2671-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3e66e03d{SSL,[ssl,
http/1.1]}{127.0.0.1:35682}
[junit4] 2> 1809002 INFO (jetty-launcher-2671-thread-1) [ ]
o.e.j.s.Server Started @1813154ms
[junit4] 2> 1809002 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35682}
[junit4] 2> 1809004 INFO (jetty-launcher-2671-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4be5ed2a{/solr,null,AVAILABLE}
[junit4] 2> 1809004 ERROR (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:55:36.342Z
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7c40459b{SSL,[ssl,
http/1.1]}{127.0.0.1:60807}
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-2) [ ]
o.e.j.s.Server Started @1813157ms
[junit4] 2> 1809005 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=60807}
[junit4] 2> 1809005 ERROR (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1809006 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1809006 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1809006 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1809006 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-23T08:55:36.343Z
[junit4] 2> 1809007 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1809030 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1809036 INFO (jetty-launcher-2671-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36008/solr
[junit4] 2> 1809036 INFO (jetty-launcher-2671-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36008/solr
[junit4] 2> 1809114 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1809114 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1809114 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:60807_solr
[junit4] 2> 1809115 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35682_solr
[junit4] 2> 1809115 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.c.Overseer Overseer
(id=98356437727576069-127.0.0.1:60807_solr-n_0000000000) starting
[junit4] 2> 1809116 INFO
(zkCallback-2683-thread-1-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1809116 INFO
(zkCallback-2682-thread-1-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1809122 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60807_solr
[junit4] 2> 1809123 INFO
(zkCallback-2683-thread-1-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1809123 INFO
(zkCallback-2682-thread-1-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1809258 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35682.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809261 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35682.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809261 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35682.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809261 INFO (jetty-launcher-2671-thread-1)
[n:127.0.0.1:35682_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/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node1/.
[junit4] 2> 1809265 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_60807.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809269 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_60807.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809269 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_60807.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1809269 INFO (jetty-launcher-2671-thread-2)
[n:127.0.0.1:60807_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/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node2/.
[junit4] 2> 1809337 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1809338 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36008/solr ready
[junit4] 2> 1809340 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.TestPullReplica Using legacyCloud?: false
[junit4] 2> 1809382 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with
params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 1809383 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1809428 INFO
(TEST-TestPullReplica.testKillPullReplica-seed#[58BD45E96E63CC7E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testKillPullReplica
[junit4] 2> 1809430 INFO (qtp1024390102-17413) [n:127.0.0.1:35682_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=1&replicationFactor=1&collection.configName=conf&maxShardsPerNode=100&name=pull_replica_test_kill_pull_replica&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1809432 INFO
(OverseerThreadFactory-7005-thread-1-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.CreateCollectionCmd Create collection
pull_replica_test_kill_pull_replica
[junit4] 2> 1809536 INFO
(OverseerStateUpdate-98356437727576069-127.0.0.1:60807_solr-n_0000000000)
[n:127.0.0.1:60807_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_test_kill_pull_replica",
[junit4] 2> "shard":"shard1",
[junit4] 2>
"core":"pull_replica_test_kill_pull_replica_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:60807/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1809538 INFO
(OverseerStateUpdate-98356437727576069-127.0.0.1:60807_solr-n_0000000000)
[n:127.0.0.1:60807_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_test_kill_pull_replica",
[junit4] 2> "shard":"shard1",
[junit4] 2>
"core":"pull_replica_test_kill_pull_replica_shard1_replica_p1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35682/solr";,
[junit4] 2> "type":"PULL"}
[junit4] 2> 1809751 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=pull_replica_test_kill_pull_replica_shard1_replica_p1&action=CREATE&numShards=1&collection=pull_replica_test_kill_pull_replica&shard=shard1&wt=javabin&version=2&replicaType=PULL
[junit4] 2> 1809751 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1809771 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf&newCollection=true&name=pull_replica_test_kill_pull_replica_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_test_kill_pull_replica&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1809771 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1809874 INFO
(zkCallback-2683-thread-1-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/pull_replica_test_kill_pull_replica/state.json] for
collection [pull_replica_test_kill_pull_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 1809874 INFO
(zkCallback-2682-thread-2-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/pull_replica_test_kill_pull_replica/state.json] for
collection [pull_replica_test_kill_pull_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 1809874 INFO
(zkCallback-2682-thread-1-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/pull_replica_test_kill_pull_replica/state.json] for
collection [pull_replica_test_kill_pull_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 1809875 INFO
(zkCallback-2683-thread-2-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/pull_replica_test_kill_pull_replica/state.json] for
collection [pull_replica_test_kill_pull_replica] has occurred - updating...
(live nodes size: [2])
[junit4] 2> 1810760 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1810765 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.s.IndexSchema
[pull_replica_test_kill_pull_replica_shard1_replica_p1] Schema name=minimal
[junit4] 2> 1810768 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1810768 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.CoreContainer
Creating SolrCore 'pull_replica_test_kill_pull_replica_shard1_replica_p1' using
configuration from collection pull_replica_test_kill_pull_replica, trusted=true
[junit4] 2> 1810768 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35682.solr.core.pull_replica_test_kill_pull_replica.shard1.replica_p1'
(registry 'solr.core.pull_replica_test_kill_pull_replica.shard1.replica_p1')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1810768 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1810768 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.SolrCore
[[pull_replica_test_kill_pull_replica_shard1_replica_p1] ] Opening new SolrCore
at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node1/pull_replica_test_kill_pull_replica_shard1_replica_p1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node1/./pull_replica_test_kill_pull_replica_shard1_replica_p1/data/]
[junit4] 2> 1810779 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1810799 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.s.IndexSchema
[pull_replica_test_kill_pull_replica_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1810801 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1810801 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'pull_replica_test_kill_pull_replica_shard1_replica_n1' using
configuration from collection pull_replica_test_kill_pull_replica, trusted=true
[junit4] 2> 1810801 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_60807.solr.core.pull_replica_test_kill_pull_replica.shard1.replica_n1'
(registry 'solr.core.pull_replica_test_kill_pull_replica.shard1.replica_n1')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6883ae3a
[junit4] 2> 1810801 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1810801 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SolrCore
[[pull_replica_test_kill_pull_replica_shard1_replica_n1] ] Opening new SolrCore
at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node2/pull_replica_test_kill_pull_replica_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001/tempDir-001/node2/./pull_replica_test_kill_pull_replica_shard1_replica_n1/data/]
[junit4] 2> 1810821 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1810821 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1810822 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5f486b3d[pull_replica_test_kill_pull_replica_shard1_replica_p1] main]
[junit4] 2> 1810823 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1810823 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1810824 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1810824 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.ZkController
pull_replica_test_kill_pull_replica_shard1_replica_p1 starting background
replication from leader
[junit4] 2> 1810824 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll
interval: 00:00:03
[junit4] 2> 1810825 INFO
(searcherExecutor-7010-thread-1-processing-n:127.0.0.1:35682_solr
x:pull_replica_test_kill_pull_replica_shard1_replica_p1 s:shard1
c:pull_replica_test_kill_pull_replica r:core_node2) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1] o.a.s.c.SolrCore
[pull_replica_test_kill_pull_replica_shard1_replica_p1] Registered new searcher
Searcher@5f486b3d[pull_replica_test_kill_pull_replica_shard1_replica_p1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1810825 INFO (qtp1024390102-17411) [n:127.0.0.1:35682_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node2
x:pull_replica_test_kill_pull_replica_shard1_replica_p1]
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 1810834 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1810834 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1810835 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1810835 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1810836 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@546bf616[pull_replica_test_kill_pull_replica_shard1_replica_n1] main]
[junit4] 2> 1810837 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1810837 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1810838 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1810839 INFO
(searcherExecutor-7011-thread-1-processing-n:127.0.0.1:60807_solr
x:pull_replica_test_kill_pull_replica_shard1_replica_n1 s:shard1
c:pull_replica_test_kill_pull_replica r:core_node1) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SolrCore
[pull_replica_test_kill_pull_replica_shard1_replica_n1] Registered new searcher
Searcher@546bf616[pull_replica_test_kill_pull_replica_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1810839 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1573703005688037376
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync replicas to
https://127.0.0.1:60807/solr/pull_replica_test_kill_pull_replica_shard1_replica_n1/
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pull_replica s:shard1 r:core_node1
x:pull_replica_test_kill_pull_replica_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:60807/solr/pull_replica_test_kill_pull_replica_shard1_replica_n1/
has no replicas
[junit4] 2> 1810844 INFO (qtp1648808824-17419) [n:127.0.0.1:60807_solr
c:pull_replica_test_kill_pul
[...truncated too long message...]
.1:35682}
[junit4] 2> 1955986 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1654131513
[junit4] 2> 1955986 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1955986 INFO (jetty-closer-2672-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1955988 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1955989 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1955992 WARN
(zkCallback-2700-thread-2-processing-n:127.0.0.1:60807_solr)
[n:127.0.0.1:60807_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1955992 INFO (jetty-closer-2672-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6cf921b4{/solr,null,UNAVAILABLE}
[junit4] 2> 1955993 INFO
(zkCallback-2694-thread-5-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 1955993 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1955996 INFO (jetty-closer-2672-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98356437727576075-127.0.0.1:35682_solr-n_0000000002) closing
[junit4] 2> 1955996 INFO
(OverseerStateUpdate-98356437727576075-127.0.0.1:35682_solr-n_0000000002)
[n:127.0.0.1:35682_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:35682_solr
[junit4] 2> 1955997 WARN
(zkCallback-2694-thread-5-processing-n:127.0.0.1:35682_solr)
[n:127.0.0.1:35682_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1955997 INFO (jetty-closer-2672-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@249ebd66{/solr,null,UNAVAILABLE}
[junit4] 2> 1955997 ERROR
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-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> 1955998 INFO
(SUITE-TestPullReplica-seed#[58BD45E96E63CC7E]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36008 36008
[junit4] 2> 1956086 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36008 36008
[junit4] 2> 1956089 WARN (Thread-3725) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/configs/conf
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 24
/solr/collections/pull_replica_test_create_delete/state.json
[junit4] 2> 20
/solr/collections/pull_replica_test_kill_leader/state.json
[junit4] 2> 20
/solr/collections/pull_replica_test_remove_all_writer_replicas/state.json
[junit4] 2> 20
/solr/collections/pull_replica_test_kill_pull_replica/state.json
[junit4] 2> 19
/solr/collections/pull_replica_test_add_remove_pull_replica/state.json
[junit4] 2> 13
/solr/collections/pull_replica_test_add_docs/state.json
[junit4] 2> 13
/solr/collections/pull_replica_test_real_time_get/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/98356437727576069-127.0.0.1:60807_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[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/J0/temp/solr.cloud.TestPullReplica_58BD45E96E63CC7E-001
[junit4] 2> Jul 23, 2017 8:58:03 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are:
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=2, maxDocsPerChunk=888, blockSize=9),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=2, blockSize=9)), sim=RandomSimilarity(queryNorm=false): {},
locale=und, timezone=NST
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=153411856,total=525336576
[junit4] 2> NOTE: All tests run in this JVM: [TestDocTermOrds,
TestSlowCompositeReaderWrapper, TestHttpShardHandlerFactory, TestFunctionQuery,
PeerSyncTest, BufferStoreTest, CloudExitableDirectoryReaderTest,
TestTlogReplica, GraphQueryTest, TestBinaryResponseWriter,
ChaosMonkeyNothingIsSafeTest, SolrRequestParserTest, TestConfigSets,
FieldMutatingUpdateProcessorTest, TestDistribDocBasedVersion,
AnalysisErrorHandlingTest, TestHalfAndHalfDocValues, TestTestInjection,
ManagedSchemaRoundRobinCloudTest, TestCSVLoader, SSLMigrationTest,
TestFastOutputStream, TestInitParams, TestSQLHandlerNonCloud,
MoveReplicaHDFSUlogDirTest, TestMacros, CollectionTooManyReplicasTest,
TestPushWriter, MBeansHandlerTest, TestDFISimilarityFactory,
BigEndianAscendingWordDeserializerTest, RecoveryAfterSoftCommitTest,
DeleteShardTest, MigrateRouteKeyTest, TestRandomRequestDistribution,
CreateCollectionCleanupTest, OverseerStatusTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, TestHashQParserPlugin,
HdfsWriteToMultipleCollectionsTest, TestReqParamsAPI, TestMergePolicyConfig,
RuleEngineTest, TestOmitPositions, DistribDocExpirationUpdateProcessorTest,
TestStressCloudBlindAtomicUpdates, TestNumericRangeQuery32,
HdfsDirectoryFactoryTest, ExplicitHLLTest, AnalyticsMergeStrategyTest,
TestRandomFaceting, CoreAdminCreateDiscoverTest, TestFieldCacheSort,
TestFaceting, TestManagedSynonymFilterFactory, TestSchemaResource,
TestFieldSortValues, UUIDFieldTest, TestDynamicFieldResource,
TestFieldTypeCollectionResource, RequestHandlersTest, TestRandomDVFaceting,
TestRequestForwarding, TestFieldCacheWithThreads, TestSolrXml,
TestReloadDeadlock, LargeFieldTest, TestPhraseSuggestions,
ClassificationUpdateProcessorFactoryTest, TestBlendedInfixSuggestions,
CdcrBootstrapTest, TestCollectionsAPIViaSolrCloudCluster, TestSolr4Spatial,
DateRangeFieldTest, TestSolrCloudWithHadoopAuthPlugin,
TestWordDelimiterFilterFactory, TestSmileRequest, TestIntervalFaceting,
SecurityConfHandlerTest, TestRealTimeGet, SolrJmxReporterTest, HLLUtilTest,
RAMDirectoryFactoryTest, LeaderFailureAfterFreshStartTest,
DocumentAnalysisRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest,
CloudMLTQParserTest, SolrCLIZkUtilsTest, TestRemoteStreaming,
TestSegmentSorting, TestComponentsName, WordBreakSolrSpellCheckerTest,
TestPayloadScoreQParserPlugin, ZkNodePropsTest, TestWriterPerf,
HttpPartitionTest, TestLazyCores, TestSolrQueryResponse, TestNoOpRegenerator,
SharedFSAutoReplicaFailoverTest, TestConfigOverlay,
TestPHPSerializedResponseWriter, BasicAuthIntegrationTest,
TestCSVResponseWriter, HdfsThreadLeakTest, TestScoreJoinQPNoScore,
DistributedQueryComponentCustomSortTest, TestSerializedLuceneMatchVersion,
SortSpecParsingTest, DistributedSpellCheckComponentTest,
IndexBasedSpellCheckerTest, TestSha256AuthenticationProvider,
DistributedVersionInfoTest, ParsingFieldUpdateProcessorsTest,
ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest,
SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest,
ZkCLITest, TestDistributedSearch, TermVectorComponentDistributedTest, TestJoin,
HardAutoCommitTest, TestRangeQuery, TestCoreContainer, TestGroupingSearch,
QueryElevationComponentTest, TestFiltering,
DistributedQueryElevationComponentTest, TestFoldingMultitermQuery,
DocValuesTest, DebugComponentTest, TestQueryUtils, TestQueryTypes,
XmlUpdateRequestHandlerTest, TestIndexingPerformance, RequiredFieldsTest,
FieldAnalysisRequestHandlerTest, LoggingHandlerTest, SolrPluginUtilsTest,
TestJmxIntegration, UpdateRequestProcessorFactoryTest,
UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, CSVRequestHandlerTest,
SOLR749Test, ResponseLogComponentTest, MultiTermTest, SampleTest,
TestBinaryField, TestConfig, TestFuzzyAnalyzedSuggestions,
ExternalFileFieldSortTest, DirectSolrConnectionTest, TestLuceneMatchVersion,
TestXIncludeConfig, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory,
TestFastWriter, ResourceLoaderTest, PluginInfoTest,
TestSuggestSpellingConverter, SpellingQueryConverterTest, TestDocumentBuilder,
CircularListTest, TestRTGBase, SolrTestCaseJ4Test, TestCrossCoreJoin,
TestDistributedMissingSort, TestTolerantSearch, TestJettySolrRunner,
AssignTest, CleanupOldIndexTest, CollectionStateFormat2Test,
CollectionsAPIAsyncDistributedZkTest, DeleteLastCustomShardedReplicaTest,
DeleteStatusTest, DistribCursorPagingTest, ForceLeaderTest,
SharedFSAutoReplicaFailoverUtilsTest, TestAuthenticationFramework,
TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCloudRecovery,
TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys,
TestLeaderInitiatedRecoveryThread, TestLockTree, TestMiniSolrCloudClusterSSL,
TestPullReplica]
[junit4] Completed [541/727 (2!)] on J0 in 147.98s, 10 tests, 1 failure, 1
skipped <<< FAILURES!
[...truncated 43928 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
