Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2803/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.AddReplicaTest.test
Error Message:
core_node6:{"core":"addreplicatest_coll_shard1_replica_n5","base_url":"http://127.0.0.1:37269/solr","node_name":"127.0.0.1:37269_solr","state":"active","type":"NRT","force_set_state":"false"}
Stack Trace:
java.lang.AssertionError:
core_node6:{"core":"addreplicatest_coll_shard1_replica_n5","base_url":"http://127.0.0.1:37269/solr","node_name":"127.0.0.1:37269_solr","state":"active","type":"NRT","force_set_state":"false"}
at
__randomizedtesting.SeedInfo.seed([B8BCC3D38025A637:30E8FC092ED9CBCF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.AddReplicaTest.test(AddReplicaTest.java:85)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12688 lines...]
[junit4] Suite: org.apache.solr.cloud.AddReplicaTest
[junit4] 2> 277414 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/init-core-data-001
[junit4] 2> 277416 WARN
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=35 numCloses=35
[junit4] 2> 277416 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 277418 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 277419 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001
[junit4] 2> 277419 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 277419 INFO (Thread-455) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 277419 INFO (Thread-455) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 277425 ERROR (Thread-455) [ ] 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> 277519 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:42463
[junit4] 2> 277522 INFO (zkConnectionManagerCallback-994-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 277525 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100cd2bdd630000, likely client has closed socket
[junit4] 2> 277526 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 277526 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 277527 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 277527 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 277528 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1382b54{/solr,null,AVAILABLE}
[junit4] 2> 277533 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 277533 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 277533 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 277533 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@14e876b{/solr,null,AVAILABLE}
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@13b48e3{HTTP/1.1,[http/1.1]}{127.0.0.1:37269}
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.e.j.s.Server Started @277591ms
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37269}
[junit4] 2> 277534 ERROR (jetty-launcher-991-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 277534 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-25T03:35:26.485Z
[junit4] 2> 277536 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 277536 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1e46f5f{HTTP/1.1,[http/1.1]}{127.0.0.1:34219}
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.e.j.s.Server Started @277594ms
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34219}
[junit4] 2> 277537 ERROR (jetty-launcher-991-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 277537 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-25T03:35:26.488Z
[junit4] 2> 277539 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@a30b05{/solr,null,AVAILABLE}
[junit4] 2> 277539 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3ab4a8{/solr,null,AVAILABLE}
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@921519{HTTP/1.1,[http/1.1]}{127.0.0.1:36097}
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@336805{HTTP/1.1,[http/1.1]}{127.0.0.1:46359}
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.e.j.s.Server Started @277597ms
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.e.j.s.Server Started @277597ms
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36097}
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46359}
[junit4] 2> 277540 ERROR (jetty-launcher-991-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 277540 ERROR (jetty-launcher-991-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.6.0
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-25T03:35:26.491Z
[junit4] 2> 277540 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-09-25T03:35:26.491Z
[junit4] 2> 277542 INFO (zkConnectionManagerCallback-1001-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 277542 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 277564 INFO (zkConnectionManagerCallback-1002-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 277565 INFO (zkConnectionManagerCallback-996-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 277565 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 277565 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 277565 INFO (zkConnectionManagerCallback-998-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 277571 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 278588 INFO (jetty-launcher-991-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42463/solr
[junit4] 2> 278589 INFO (zkConnectionManagerCallback-1006-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278590 DEBUG (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$47/21598741@a42741
[junit4] 2> 278591 INFO (zkConnectionManagerCallback-1008-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278653 INFO (jetty-launcher-991-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42463/solr
[junit4] 2> 278654 INFO (zkConnectionManagerCallback-1014-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278655 DEBUG (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$47/21598741@37df2e
[junit4] 2> 278656 INFO (zkConnectionManagerCallback-1016-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278659 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278660 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/security.json
[junit4] 2> 278661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278661 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278834 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278834 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278834 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/security.json
[junit4] 2> 278835 DEBUG (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000
[junit4] 2> 278836 DEBUG (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/72283182319468552-127.0.0.1:34219_solr-n_0000000001
[junit4] 2> 278836 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:36097_solr
[junit4] 2> 278836 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data:
/solr/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000
[junit4] 2> 278836 DEBUG (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000 to
know if I could be the leader
[junit4] 2> 278836 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34219_solr
[junit4] 2> 278836 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.Overseer Overseer
(id=72283182319468550-127.0.0.1:36097_solr-n_0000000000) starting
[junit4] 2> 278837 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278837 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278837 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278837 INFO (zkCallback-1007-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 278837 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278837 INFO (zkCallback-1015-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 278848 INFO (zkConnectionManagerCallback-1023-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278848 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278849 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 278849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278849 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278849 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:42463/solr ready
[junit4] 2> 278851 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Process current
queue of overseer operations
[junit4] 2> 278851 INFO
(OverseerStateUpdate-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:36097_solr
[junit4] 2> 278851 DEBUG (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:36097_solr as DOWN
[junit4] 2> 278852 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/autoscaling.json
[junit4] 2> 278852 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue-work
[junit4] 2> 278852 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up
work-queue. #Running tasks: 0
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 278853 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 278853 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 278853 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 100
elements. ExcludeSet: []
[junit4] 2> 278853 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36097_solr
[junit4] 2> 278853 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 278853 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/collection-queue-work
[junit4] 2> 278854 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278854 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278854 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278854 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278854 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278854 INFO (zkCallback-1015-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 278854 INFO (zkCallback-1007-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 278856 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278856 INFO (zkCallback-1022-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 278861 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 278865 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 278866 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 278866 INFO (zkConnectionManagerCallback-1028-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278867 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278867 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278867 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 278867 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278867 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278868 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278868 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:42463/solr ready
[junit4] 2> 278868 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 278894 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34219.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278900 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36097.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278904 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34219.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278904 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34219.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278906 INFO (jetty-launcher-991-thread-2)
[n:127.0.0.1:34219_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node2/.
[junit4] 2> 278913 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36097.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278913 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36097.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 278914 INFO (jetty-launcher-991-thread-3)
[n:127.0.0.1:36097_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node3/.
[junit4] 2> 278952 INFO (jetty-launcher-991-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42463/solr
[junit4] 2> 278954 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 278955 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 278957 INFO (zkConnectionManagerCallback-1034-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278958 DEBUG (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$47/21598741@a6b76e
[junit4] 2> 278959 INFO (zkConnectionManagerCallback-1036-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278962 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278963 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278963 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 278963 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278963 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278963 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/security.json
[junit4] 2> 278965 DEBUG (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/72283182319468556-127.0.0.1:46359_solr-n_0000000002
[junit4] 2> 278965 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data:
/solr/overseer_elect/election/72283182319468552-127.0.0.1:34219_solr-n_0000000001
[junit4] 2> 278965 DEBUG (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/72283182319468552-127.0.0.1:34219_solr-n_0000000001 to
know if I could be the leader
[junit4] 2> 278965 DEBUG (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:46359_solr as DOWN
[junit4] 2> 278966 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 278966 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 278966 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46359_solr
[junit4] 2> 278966 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 278967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278968 INFO (zkCallback-1022-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 278968 INFO (zkCallback-1015-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 278968 INFO (zkCallback-1007-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 278968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278968 INFO (zkCallback-1027-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 278968 INFO (zkCallback-1035-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 278986 INFO (zkConnectionManagerCallback-1043-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 278987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 278987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 278987 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 278987 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 278988 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 278988 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 278988 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:42463/solr ready
[junit4] 2> 278988 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 279005 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46359.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279015 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46359.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279015 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46359.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279016 INFO (jetty-launcher-991-thread-4)
[n:127.0.0.1:46359_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node4/.
[junit4] 2> 279067 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 279068 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 279793 INFO (jetty-launcher-991-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42463/solr
[junit4] 2> 279794 INFO (zkConnectionManagerCallback-1048-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279795 DEBUG (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.ZkController Added new OnReconnect
listener org.apache.solr.cloud.ZkController$$Lambda$47/21598741@134c7f0
[junit4] 2> 279796 INFO (zkConnectionManagerCallback-1050-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279801 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 279801 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279801 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 279801 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 279801 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279801 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 279802 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/security.json
[junit4] 2> 279803 DEBUG (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.LeaderElector Joined leadership election
with path:
/overseer_elect/election/72283182319468559-127.0.0.1:37269_solr-n_0000000003
[junit4] 2> 279803 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data:
/solr/overseer_elect/election/72283182319468556-127.0.0.1:46359_solr-n_0000000002
[junit4] 2> 279803 DEBUG (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/72283182319468556-127.0.0.1:46359_solr-n_0000000002 to
know if I could be the leader
[junit4] 2> 279803 DEBUG (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:37269_solr as DOWN
[junit4] 2> 279804 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 279804 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 279804 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37269_solr
[junit4] 2> 279804 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 INFO (zkCallback-1027-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279805 INFO (zkCallback-1015-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279805 INFO (zkCallback-1007-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279805 INFO (zkCallback-1022-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279805 INFO (zkCallback-1042-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279805 INFO (zkCallback-1049-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279808 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279809 INFO (zkCallback-1035-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 279827 INFO (zkConnectionManagerCallback-1057-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279828 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 279828 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279828 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 279828 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 279829 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279829 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 279829 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:42463/solr ready
[junit4] 2> 279829 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 279851 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37269.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279866 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37269.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279867 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37269.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 279868 INFO (jetty-launcher-991-thread-1)
[n:127.0.0.1:37269_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node1/.
[junit4] 2> 279905 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 279905 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 279925 INFO (zkConnectionManagerCallback-1060-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279928 INFO (zkConnectionManagerCallback-1065-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279929 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/clusterprops.json
[junit4] 2> 279929 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/live_nodes
[junit4] 2> 279929 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 279929 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/clusterstate.json
[junit4] 2> 279929 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279930 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/aliases.json
[junit4] 2> 279930 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42463/solr ready
[junit4] 2> 279958 INFO
(TEST-AddReplicaTest.test-seed#[B8BCC3D38025A637]) [ ] o.a.s.SolrTestCaseJ4
###Starting test
[junit4] 2> 279960 INFO (zkConnectionManagerCallback-1067-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 279963 INFO (qtp27835910-3000) [n:127.0.0.1:37269_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf1&maxShardsPerNode=2&name=addreplicatest_coll&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 279964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/collection-queue-work
[junit4] 2> 279964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000
[junit4] 2> 279964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/collection-queue-work
[junit4] 2> 279964 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.ZkDistributedQueue Found child node with
improper name: qnr-0000000000
[junit4] 2> 279964 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/collection-queue-work
[junit4] 2> 279965 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/collection-queue-work
[junit4] 2> 279965 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.ZkDistributedQueue Found child node with
improper name: qnr-0000000000
[junit4] 2> 279965 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskQueue Returning topN
elements: [/overseer/collection-queue-work/qn-0000000000, ]
[junit4] 2> 279965 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from
work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
[junit4] 2> 279965 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Marked task
[/overseer/collection-queue-work/qn-0000000000] as running
[junit4] 2> 279965 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection
Message Handler: Get the message
id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"addreplicatest_coll",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 279966 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Runner processing
/overseer/collection-queue-work/qn-0000000000
[junit4] 2> 279966 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"addreplicatest_coll",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Cleaning up
work-queue. #Running tasks: 1
[junit4] 2> 279966 INFO
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
addreplicatest_coll
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks:
[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks:
[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 279966 DEBUG
(OverseerCollectionConfigSetProcessor-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskQueue Peeking for top 99
elements. ExcludeSet: [/overseer/collection-queue-work/qn-0000000000],[]
[junit4] 2> 279966 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
creating collections conf node /collections/addreplicatest_coll
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/collections
[junit4] 2> 279967 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Check for
collection zkNode:addreplicatest_coll
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279968 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode
exists
[junit4] 2> 279968 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/collections
[junit4] 2> 279969 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 279969 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 279970 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 279970 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 280071 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.Assign Identify nodes using default
[junit4] 2> 280071 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores
for new collection addreplicatest_coll, shardNames [shard1, shard2] , message :
{
[junit4] 2> "name":"addreplicatest_coll",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 280072 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core
addreplicatest_coll_shard1_replica_n1 as part of shard shard1 of collection
addreplicatest_coll on 127.0.0.1:34219_solr
[junit4] 2> 280073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 280073 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 280074 INFO
(OverseerStateUpdate-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"addreplicatest_coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"addreplicatest_coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34219/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 280074 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Creating core
addreplicatest_coll_shard2_replica_n2 as part of shard shard2 of collection
addreplicatest_coll on 127.0.0.1:36097_solr
[junit4] 2> 280075 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 280075 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 280075 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Expecting [addreplicatest_coll_shard1_replica_n1,
addreplicatest_coll_shard2_replica_n2] cores but found {}
[junit4] 2> 280075 INFO
(OverseerStateUpdate-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"addreplicatest_coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"addreplicatest_coll_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36097/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 280176 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Expecting [addreplicatest_coll_shard1_replica_n1,
addreplicatest_coll_shard2_replica_n2] cores but found {}
[junit4] 2> 280177 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 280178 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 280280 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
x:addreplicatest_coll_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard1_replica_n1&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 280280 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
x:addreplicatest_coll_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard2_replica_n2&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 280280 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
[junit4] 2> 280281 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 280282 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 280282 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController publishing
state=down
[junit4] 2> 280282 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController publishing
state=down
[junit4] 2> 280282 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 280283 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 280283 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController Registering watch
for collection addreplicatest_coll
[junit4] 2> 280283 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController waiting to find
shard id in clusterstate for addreplicatest_coll_shard1_replica_n1
[junit4] 2> 280283 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController Registering watch
for collection addreplicatest_coll
[junit4] 2> 280283 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController waiting to find
shard id in clusterstate for addreplicatest_coll_shard2_replica_n2
[junit4] 2> 280384 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 280384 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 280385 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 280385 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281296 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.6.0
[junit4] 2> 281296 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.6.0
[junit4] 2> 281304 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.s.IndexSchema
[addreplicatest_coll_shard2_replica_n2] Schema name=minimal
[junit4] 2> 281304 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.s.IndexSchema
[addreplicatest_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 281307 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 281307 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'addreplicatest_coll_shard1_replica_n1' using configuration from
collection addreplicatest_coll, trusted=true
[junit4] 2> 281307 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 281307 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.CoreContainer Creating
SolrCore 'addreplicatest_coll_shard2_replica_n2' using configuration from
collection addreplicatest_coll, trusted=true
[junit4] 2> 281307 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_34219.solr.core.addreplicatest_coll.shard1.replica_n1'
(registry 'solr.core.addreplicatest_coll.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 281307 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SolrCore
[[addreplicatest_coll_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node2/addreplicatest_coll_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node2/./addreplicatest_coll_shard1_replica_n1/data/]
[junit4] 2> 281308 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_36097.solr.core.addreplicatest_coll.shard2.replica_n2'
(registry 'solr.core.addreplicatest_coll.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6d6f56
[junit4] 2> 281308 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SolrCore
[[addreplicatest_coll_shard2_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node3/addreplicatest_coll_shard2_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001/tempDir-001/node3/./addreplicatest_coll_shard2_replica_n2/data/]
[junit4] 2> 281391 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 281391 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 281392 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 281392 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 281394 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@38632f[addreplicatest_coll_shard1_replica_n1] main]
[junit4] 2> 281395 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 281396 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 281397 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 281398 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1612548963407233024
[junit4] 2> 281399 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 281399 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 281400 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController watch zkdir
/configs/conf1
[junit4] 2> 281400 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/configs/conf1
[junit4] 2> 281400 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281400 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 281400 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 281402 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/collections/addreplicatest_coll/terms/shard1
[junit4] 2> 281403 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1d974fb[addreplicatest_coll_shard2_replica_n2] main]
[junit4] 2> 281403 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/terms/shard1
[junit4] 2> 281403 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update
of terms at /collections/addreplicatest_coll/terms/shard1 to
Terms{values={core_node3=0}, version=0}
[junit4] 2> 281403 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController Register replica
- core:addreplicatest_coll_shard1_replica_n1
address:http://127.0.0.1:34219/solr collection:addreplicatest_coll shard:shard1
[junit4] 2> 281403 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/collections/addreplicatest_coll/terms/shard1
[junit4] 2> 281403 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 281404 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 281405 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 281405 INFO
(searcherExecutor-1306-thread-1-processing-n:127.0.0.1:34219_solr
x:addreplicatest_coll_shard1_replica_n1 c:addreplicatest_coll s:shard1
r:core_node3) [n:127.0.0.1:34219_solr c:addreplicatest_coll s:shard1
r:core_node3 x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SolrCore
[addreplicatest_coll_shard1_replica_n1] Registered new searcher
Searcher@38632f[addreplicatest_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 281406 INFO
(searcherExecutor-1307-thread-1-processing-n:127.0.0.1:36097_solr
x:addreplicatest_coll_shard2_replica_n2 c:addreplicatest_coll s:shard2
r:core_node4) [n:127.0.0.1:36097_solr c:addreplicatest_coll s:shard2
r:core_node4 x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SolrCore
[addreplicatest_coll_shard2_replica_n2] Registered new searcher
Searcher@1d974fb[addreplicatest_coll_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 281406 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1612548963415621632
[junit4] 2> 281408 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.LeaderElector Joined
leadership election with path:
/collections/addreplicatest_coll/leader_elect/shard1/election/72283182319468552-core_node3-n_0000000000
[junit4] 2> 281408 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController watch zkdir
/configs/conf1
[junit4] 2> 281408 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/configs/conf1
[junit4] 2> 281408 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Running the leader process for shard=shard1 and weAreReplacement=false and
leaderVoteWait=10000
[junit4] 2> 281408 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281408 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 281409 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 281409 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:34219/solr/addreplicatest_coll_shard1_replica_n1/
[junit4] 2> 281409 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 281409 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:34219/solr/addreplicatest_coll_shard1_replica_n1/ has no
replicas
[junit4] 2> 281410 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
127.0.0.1:34219_solr synched 0
[junit4] 2> 281410 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 281410 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/collections/addreplicatest_coll/terms/shard2
[junit4] 2> 281411 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/terms/shard2
[junit4] 2> 281411 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update
of terms at /collections/addreplicatest_coll/terms/shard2 to
Terms{values={core_node4=0}, version=0}
[junit4] 2> 281411 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController Register replica
- core:addreplicatest_coll_shard2_replica_n2
address:http://127.0.0.1:36097/solr collection:addreplicatest_coll shard:shard2
[junit4] 2> 281411 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: create/delete: /solr/collections/addreplicatest_coll/terms/shard2
[junit4] 2> 281412 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase
Creating leader registration node
/collections/addreplicatest_coll/leaders/shard1/leader after winning as
/collections/addreplicatest_coll/leader_elect/shard1/election/72283182319468552-core_node3-n_0000000000
[junit4] 2> 281413 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 281413 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.LeaderElector Joined
leadership election with path:
/collections/addreplicatest_coll/leader_elect/shard2/election/72283182319468550-core_node4-n_0000000000
[junit4] 2> 281413 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
http://127.0.0.1:34219/solr/addreplicatest_coll_shard1_replica_n1/ shard1
[junit4] 2> 281413 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281413 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
Running the leader process for shard=shard2 and weAreReplacement=false and
leaderVoteWait=10000
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:36097/solr/addreplicatest_coll_shard2_replica_n2/
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.SyncStrategy
http://127.0.0.1:36097/solr/addreplicatest_coll_shard2_replica_n2/ has no
replicas
[junit4] 2> 281414 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
127.0.0.1:36097_solr synched 0
[junit4] 2> 281414 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 281415 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase
Creating leader registration node
/collections/addreplicatest_coll/leaders/shard2/leader after winning as
/collections/addreplicatest_coll/leader_elect/shard2/election/72283182319468550-core_node4-n_0000000000
[junit4] 2> 281416 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 281416 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
http://127.0.0.1:36097/solr/addreplicatest_coll_shard2_replica_n2/ shard2
[junit4] 2> 281416 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281517 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281517 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281518 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 281518 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController We are
http://127.0.0.1:36097/solr/addreplicatest_coll_shard2_replica_n2/ and leader
is http://127.0.0.1:36097/solr/addreplicatest_coll_shard2_replica_n2/
[junit4] 2> 281518 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController No LogReplay
needed for core=addreplicatest_coll_shard2_replica_n2
baseURL=http://127.0.0.1:36097/solr
[junit4] 2> 281518 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 281518 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281518 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController publishing
state=active
[junit4] 2> 281518 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController Found a
registered searcher: Searcher@1d974fb[addreplicatest_coll_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core:
org.apache.solr.core.SolrCore@147f7bf
[junit4] 2> 281518 DEBUG (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController Found newest
searcher: Searcher@1d974fb[addreplicatest_coll_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core:
addreplicatest_coll_shard2_replica_n2, id: org.apache.solr.core.SolrCore@147f7bf
[junit4] 2> 281519 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 281519 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281519 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281520 INFO (qtp22413186-3019) [n:127.0.0.1:36097_solr
c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard2_replica_n2&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1240
[junit4] 2> 281565 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController We are
http://127.0.0.1:34219/solr/addreplicatest_coll_shard1_replica_n1/ and leader
is http://127.0.0.1:34219/solr/addreplicatest_coll_shard1_replica_n1/
[junit4] 2> 281566 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController No LogReplay
needed for core=addreplicatest_coll_shard1_replica_n1
baseURL=http://127.0.0.1:34219/solr
[junit4] 2> 281566 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 281566 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController publishing
state=active
[junit4] 2> 281566 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController Found a
registered searcher: Searcher@38632f[addreplicatest_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core:
org.apache.solr.core.SolrCore@53a081
[junit4] 2> 281566 DEBUG (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController Found newest
searcher: Searcher@38632f[addreplicatest_coll_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core:
addreplicatest_coll_shard1_replica_n1, id: org.apache.solr.core.SolrCore@53a081
[junit4] 2> 281566 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/queue
[junit4] 2> 281566 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/queue
[junit4] 2> 281567 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281567 INFO (qtp30168030-3008) [n:127.0.0.1:34219_solr
c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard1_replica_n1&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1288
[junit4] 2> 281568 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.a.c.CreateCollectionCmd Finished create
command on all shards for collection: addreplicatest_coll
[junit4] 2> 281568 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Completed
task:[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 281569 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/overseer/collection-queue-work/qnr-0000000000
[junit4] 2> 281569 DEBUG (zkCallback-1049-thread-1) [ ]
o.a.s.c.OverseerTaskQueue NodeDataChanged fired on path
/overseer/collection-queue-work/qnr-0000000000 state SyncConnected
latchEventType null
[junit4] 2> 281569 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: children: /solr/overseer/collection-queue-work
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Marked task
[/overseer/collection-queue-work/qn-0000000000] as completed.
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor CompletedTasks:
[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor RunningZKTasks:
[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 281569 DEBUG
(OverseerThreadFactory-1284-thread-1-processing-n:127.0.0.1:36097_solr)
[n:127.0.0.1:36097_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection
Message Handler: Message id:/overseer/collection-queue-work/qn-0000000000
complete,
response:{success={127.0.0.1:36097_solr={responseHeader={status=0,QTime=1240},core=addreplicatest_coll_shard2_replica_n2},127.0.0.1:34219_solr={responseHeader={status=0,QTime=1288},core=addreplicatest_coll_shard1_replica_n1}}}
[junit4] 2> 281570 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: children: /solr/overseer/collection-queue-work
[junit4] 2> 281570 INFO (qtp27835910-3000) [n:127.0.0.1:37269_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 281667 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: data: /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 281668 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired:
[...truncated too long message...]
icManager Closing metric reporters for
registry=solr.core.addreplicatest_coll.shard1.replica_n5, tag=1bed123
[junit4] 2> 286148 INFO (coreCloseExecutor-1324-thread-1)
[n:127.0.0.1:37269_solr c:addreplicatest_coll s:shard1 r:core_node6
x:addreplicatest_coll_shard1_replica_n5] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@81d256: rootName =
solr_37269, domain = solr.core.addreplicatest_coll.shard1.replica_n5, service
url = null, agent id = null] for registry
solr.core.addreplicatest_coll.shard1.replica_n5 /
com.codahale.metrics.MetricRegistry@9d75d0
[junit4] 2> 286154 INFO (coreCloseExecutor-1321-thread-1)
[n:127.0.0.1:34219_solr c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.addreplicatest_coll.shard1.leader, tag=53a081
[junit4] 2> 286154 DEBUG (coreCloseExecutor-1321-thread-1)
[n:127.0.0.1:34219_solr c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController removed listener
for config directory [/configs/conf1]
[junit4] 2> 286154 DEBUG (coreCloseExecutor-1321-thread-1)
[n:127.0.0.1:34219_solr c:addreplicatest_coll s:shard1 r:core_node3
x:addreplicatest_coll_shard1_replica_n1] o.a.s.c.ZkController No more listeners
for config directory [/configs/conf1]
[junit4] 2> 286162 INFO (coreCloseExecutor-1322-thread-1)
[n:127.0.0.1:36097_solr c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.addreplicatest_coll.shard2.leader, tag=147f7bf
[junit4] 2> 286162 DEBUG (coreCloseExecutor-1322-thread-1)
[n:127.0.0.1:36097_solr c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController removed listener
for config directory [/configs/conf1]
[junit4] 2> 286162 DEBUG (coreCloseExecutor-1322-thread-1)
[n:127.0.0.1:36097_solr c:addreplicatest_coll s:shard2 r:core_node4
x:addreplicatest_coll_shard2_replica_n2] o.a.s.c.ZkController No more listeners
for config directory [/configs/conf1]
[junit4] 2> 286167 INFO (jetty-closer-992-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72283182319468550-127.0.0.1:36097_solr-n_0000000000) closing
[junit4] 2> 286167 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: create/delete:
/solr/overseer_elect/election/72283182319468552-127.0.0.1:34219_solr-n_0000000001
[junit4] 2> 286167 INFO
(OverseerStateUpdate-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[n:127.0.0.1:36097_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:36097_solr
[junit4] 2> 286167 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: create/delete:
/solr/collections/addreplicatest_coll/leader_elect/shard1/election/72283182319468552-core_node3-n_0000000000
[junit4] 2> 286167 WARN
(OverseerAutoScalingTriggerThread-72283182319468550-127.0.0.1:36097_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 286167 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x100cd2bdd630008, likely client has closed socket
[junit4] 2> 286167 INFO (jetty-closer-992-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@14e876b{/solr,null,UNAVAILABLE}
[junit4] 2> 286168 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
added: data:
/solr/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000
[junit4] 2> 286168 INFO (jetty-closer-992-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 286168 DEBUG (zkCallback-1049-thread-1) [ ]
o.a.s.c.LeaderElector Watching path
/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000 to
know if I could be the leader
[junit4] 2> 286170 DEBUG (SyncThread:0) [ ] o.a.s.c.ZkTestServer Watch
fired: create/delete:
/solr/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000
[junit4] 2> 286170 INFO (coreCloseExecutor-1324-thread-1)
[n:127.0.0.1:37269_solr c:addreplicatest_coll s:shard1 r:core_node6
x:addreplicatest_coll_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.addreplicatest_coll.shard1.leader, tag=1bed123
[junit4] 2> 286170 DEBUG (coreCloseExecutor-1324-thread-1)
[n:127.0.0.1:37269_solr c:addreplicatest_coll s:shard1 r:core_node6
x:addreplicatest_coll_shard1_replica_n5] o.a.s.c.ZkController removed listener
for config directory [/configs/conf1]
[junit4] 2> 286170 DEBUG (coreCloseExecutor-1324-thread-1)
[n:127.0.0.1:37269_solr c:addreplicatest_coll s:shard1 r:core_node6
x:addreplicatest_coll_shard1_replica_n5] o.a.s.c.ZkController No more listeners
for config directory [/configs/conf1]
[junit4] 2> 286170 INFO (zkCallback-1049-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37269_solr
[junit4] 2> 286170 INFO (jetty-closer-992-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@a30b05{/solr,null,UNAVAILABLE}
[junit4] 2> 286170 INFO (jetty-closer-992-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 286173 INFO (jetty-closer-992-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1382b54{/solr,null,UNAVAILABLE}
[junit4] 2> 286173 INFO (jetty-closer-992-thread-4) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 286176 ERROR
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-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> 286177 INFO
(SUITE-AddReplicaTest-seed#[B8BCC3D38025A637]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:42463 42463
[junit4] 2> 286202 INFO (Thread-455) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:42463 42463
[junit4] 2> 286203 WARN (Thread-455) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/aliases.json
[junit4] 2> 9
/solr/collections/addreplicatest_coll/terms/shard1
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2> 2
/solr/collections/addreplicatest_coll/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 9 /solr/collections/addreplicatest_coll/state.json
[junit4] 2> 2
/solr/overseer_elect/election/72283182319468550-127.0.0.1:36097_solr-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72283182319468552-127.0.0.1:34219_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/live_nodes
[junit4] 2> 9 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.AddReplicaTest_B8BCC3D38025A637-001
[junit4] 2> Sep 25, 2018 3:35:35 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 9 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1543, maxMBSortInHeap=5.50866455608498,
sim=RandomSimilarity(queryNorm=false): {}, locale=sl, timezone=Pacific/Yap
[junit4] 2> NOTE: Linux 4.15.0-32-generic i386/Oracle Corporation
1.8.0_172 (32-bit)/cpus=8,threads=1,free=261373568,total=520093696
[junit4] 2> NOTE: All tests run in this JVM:
[WrapperMergePolicyFactoryTest, HttpPartitionTest, TestShortCircuitedRequests,
TestMinMaxOnMultiValuedField, TestQueryUtils, SolrTestCaseJ4Test,
TestCollationField, SpatialRPTFieldTypeTest, CachingDirectoryFactoryTest,
JavabinLoaderTest, TestDocSet, TestTrie, TestSimComputePlanAction,
OpenCloseCoreStressTest, CdcrBootstrapTest, IndexSchemaRuntimeFieldTest,
Tagger2Test, TestStressRecovery, TestDownShardTolerantSearch,
TestFuzzyAnalyzedSuggestions, HighlighterMaxOffsetTest, TestTestInjection,
TestExactSharedStatsCache, SchemaApiFailureTest, SmileWriterTest,
TestHashQParserPlugin, TestStressVersions, DistributedFacetPivotSmallTest,
TestDirectoryFactory, OverseerTest, TestJsonFacetRefinement, AddReplicaTest]
[junit4] Completed [83/833 (1!)] on J2 in 9.07s, 1 test, 1 failure <<<
FAILURES!
[...truncated 47583 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/2803/consoleText
[repro] Revision: eeaf6fc7d10a66ad1687a901882a89d987443c24
[repro] Ant options: "-Dargs=-client -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=AddReplicaTest -Dtests.method=test
-Dtests.seed=B8BCC3D38025A637 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=sl -Dtests.timezone=Pacific/Yap -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] AddReplicaTest
[repro] ant compile-test
[...truncated 3437 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.AddReplicaTest" -Dtests.showOutput=onerror "-Dargs=-client
-XX:+UseG1GC" -Dtests.seed=B8BCC3D38025A637 -Dtests.multiplier=3
-Dtests.slow=true -Dtests.locale=sl -Dtests.timezone=Pacific/Yap
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 71 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.AddReplicaTest
[repro] Exiting with code 0
[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
