Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20885/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.impl.CloudSolrClientTest
Error Message:
There are still nodes recoverying - waited for 30 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30
seconds
at __randomizedtesting.SeedInfo.seed([C7150671B82F8B1C]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.setupCluster(CloudSolrClientTest.java:104)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.LeaderElectionIntegrationTest.testSimpleSliceLeaderElection
Error Message:
Error from server at https://127.0.0.1:35615/solr: Timed out waiting to see all
replicas: [collection2_shard1_replica_n9] in cluster state.
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at https://127.0.0.1:35615/solr: Timed out waiting to see all
replicas: [collection2_shard1_replica_n9] in cluster state.
at
__randomizedtesting.SeedInfo.seed([DA31CFF668403EA6:840502B82B3FA241]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at
org.apache.solr.cloud.LeaderElectionIntegrationTest.createCollection(LeaderElectionIntegrationTest.java:57)
at
org.apache.solr.cloud.LeaderElectionIntegrationTest.testSimpleSliceLeaderElection(LeaderElectionIntegrationTest.java:116)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12434 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderElectionIntegrationTest
[junit4] 2> 712173 INFO
(SUITE-LeaderElectionIntegrationTest-seed#[DA31CFF668403EA6]-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-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/init-core-data-001
[junit4] 2> 712173 WARN
(SUITE-LeaderElectionIntegrationTest-seed#[DA31CFF668403EA6]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 712173 INFO
(SUITE-LeaderElectionIntegrationTest-seed#[DA31CFF668403EA6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 712175 INFO
(SUITE-LeaderElectionIntegrationTest-seed#[DA31CFF668403EA6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 712177 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testSimpleSliceLeaderElection
[junit4] 2> 712178 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 6 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001
[junit4] 2> 712178 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 712178 INFO (Thread-2956) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 712178 INFO (Thread-2956) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 712180 ERROR (Thread-2956) [ ] 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> 712278 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.c.ZkTestServer start zk server on port:45129
[junit4] 2> 712283 INFO (jetty-launcher-2778-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712283 INFO (jetty-launcher-2778-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712283 INFO (jetty-launcher-2778-thread-3) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712284 INFO (jetty-launcher-2778-thread-4) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712285 INFO (jetty-launcher-2778-thread-5) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712304 INFO (jetty-launcher-2778-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5dadc4b7{/solr,null,AVAILABLE}
[junit4] 2> 712306 INFO (jetty-launcher-2778-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3f4e90ce{SSL,[ssl,
http/1.1]}{127.0.0.1:35615}
[junit4] 2> 712306 INFO (jetty-launcher-2778-thread-3) [ ]
o.e.j.s.Server Started @713501ms
[junit4] 2> 712306 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=35615}
[junit4] 2> 712306 ERROR (jetty-launcher-2778-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712306 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712307 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712307 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712307 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.783Z
[junit4] 2> 712308 INFO (jetty-launcher-2778-thread-6) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 712310 INFO (jetty-launcher-2778-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@701f21bb{/solr,null,AVAILABLE}
[junit4] 2> 712310 INFO (jetty-launcher-2778-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@29ae013d{SSL,[ssl,
http/1.1]}{127.0.0.1:41927}
[junit4] 2> 712310 INFO (jetty-launcher-2778-thread-2) [ ]
o.e.j.s.Server Started @713505ms
[junit4] 2> 712310 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41927}
[junit4] 2> 712310 ERROR (jetty-launcher-2778-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712310 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712311 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712311 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712311 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.787Z
[junit4] 2> 712317 INFO (jetty-launcher-2778-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@226b21e8{/solr,null,AVAILABLE}
[junit4] 2> 712317 INFO (jetty-launcher-2778-thread-4) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@4c8711e2{SSL,[ssl,
http/1.1]}{127.0.0.1:46391}
[junit4] 2> 712317 INFO (jetty-launcher-2778-thread-4) [ ]
o.e.j.s.Server Started @713513ms
[junit4] 2> 712317 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46391}
[junit4] 2> 712318 ERROR (jetty-launcher-2778-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712318 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712318 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712318 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712318 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.794Z
[junit4] 2> 712323 INFO (jetty-launcher-2778-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6e0d2ce6{/solr,null,AVAILABLE}
[junit4] 2> 712324 INFO (jetty-launcher-2778-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@58b1a0ed{/solr,null,AVAILABLE}
[junit4] 2> 712324 INFO (jetty-launcher-2778-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@33082679{SSL,[ssl,
http/1.1]}{127.0.0.1:33045}
[junit4] 2> 712324 INFO (jetty-launcher-2778-thread-1) [ ]
o.e.j.s.Server Started @713519ms
[junit4] 2> 712324 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33045}
[junit4] 2> 712324 ERROR (jetty-launcher-2778-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712324 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712325 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712325 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712325 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.801Z
[junit4] 2> 712326 INFO (jetty-launcher-2778-thread-6) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@71e918f8{/solr,null,AVAILABLE}
[junit4] 2> 712326 INFO (jetty-launcher-2778-thread-6) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1518bf39{SSL,[ssl,
http/1.1]}{127.0.0.1:34829}
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.e.j.s.Server Started @713522ms
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34829}
[junit4] 2> 712327 ERROR (jetty-launcher-2778-thread-6) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712327 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.803Z
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7716b584{SSL,[ssl,
http/1.1]}{127.0.0.1:33973}
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.e.j.s.Server Started @713524ms
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33973}
[junit4] 2> 712329 ERROR (jetty-launcher-2778-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 712329 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-11T04:04:20.805Z
[junit4] 2> 712330 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712337 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712337 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712342 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712342 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15fa93ff02f0003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 712349 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15fa93ff02f0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 712349 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712350 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 712354 INFO (jetty-launcher-2778-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712363 INFO (jetty-launcher-2778-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712364 INFO (jetty-launcher-2778-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712365 INFO (jetty-launcher-2778-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712374 INFO (jetty-launcher-2778-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712382 INFO (jetty-launcher-2778-thread-6) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45129/solr
[junit4] 2> 712416 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712434 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:35615_solr
[junit4] 2> 712435 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712436 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712437 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.c.Overseer Overseer
(id=98983808903086097-127.0.0.1:35615_solr-n_0000000000) starting
[junit4] 2> 712437 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33973_solr
[junit4] 2> 712437 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34829_solr
[junit4] 2> 712437 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712441 INFO
(zkCallback-2812-thread-1-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 712442 INFO
(zkCallback-2809-thread-1-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 712442 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 712449 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 712450 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 712451 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41927_solr
[junit4] 2> 712452 INFO
(zkCallback-2809-thread-2-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 712461 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 712462 INFO
(zkCallback-2809-thread-1-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 712462 INFO
(zkCallback-2812-thread-1-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 712462 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 712483 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 712483 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712484 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46391_solr
[junit4] 2> 712485 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 712485 INFO
(zkCallback-2809-thread-1-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 712485 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 712487 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 712487 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 712488 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33045_solr
[junit4] 2> 712501 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 712501 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 712502 INFO
(zkCallback-2809-thread-1-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 712513 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (5)
[junit4] 2> 712513 INFO
(zkCallback-2806-thread-1-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 712518 INFO
(zkCallback-2812-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (5)
[junit4] 2> 712518 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35615_solr
[junit4] 2> 712518 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712518 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712518 INFO
(zkCallback-2806-thread-1-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712519 INFO
(zkCallback-2809-thread-1-processing-n:127.0.0.1:34829_solr)
[n:127.0.0.1:34829_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712519 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712528 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41927.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712529 INFO
(zkCallback-2812-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 712552 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41927.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712552 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41927.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712553 INFO (jetty-launcher-2778-thread-2)
[n:127.0.0.1:41927_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node2/.
[junit4] 2> 712593 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34829.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712595 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33973.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712606 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34829.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712606 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34829.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712608 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33045.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712609 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33973.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712609 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33973.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712610 INFO (jetty-launcher-2778-thread-5)
[n:127.0.0.1:33973_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node5/.
[junit4] 2> 712612 INFO (jetty-launcher-2778-thread-6)
[n:127.0.0.1:34829_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node6/.
[junit4] 2> 712617 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46391.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712621 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33045.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712621 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33045.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712622 INFO (jetty-launcher-2778-thread-1)
[n:127.0.0.1:33045_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node1/.
[junit4] 2> 712626 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46391.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712626 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46391.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712627 INFO (jetty-launcher-2778-thread-4)
[n:127.0.0.1:46391_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node4/.
[junit4] 2> 712628 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35615.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712636 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35615.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712636 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35615.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 712637 INFO (jetty-launcher-2778-thread-3)
[n:127.0.0.1:35615_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node3/.
[junit4] 2> 712866 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6)
[junit4] 2> 712866 INFO
(TEST-LeaderElectionIntegrationTest.testSimpleSliceLeaderElection-seed#[DA31CFF668403EA6])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:45129/solr ready
[junit4] 2> 712909 INFO (qtp1816923574-14639) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 712910 INFO
(OverseerThreadFactory-3248-thread-1-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 713016 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41927/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 713017 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46391/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 713250 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 713250 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 713258 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 713258 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 713361 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 713361 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 713361 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 713361 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714260 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 714267 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 714267 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 714270 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 714270 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using
configuration from collection collection1, trusted=true
[junit4] 2> 714270 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41927.solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 714270 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 714271 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node2/collection1_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
[junit4] 2> 714273 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=minimal
[junit4] 2> 714286 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 714286 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using
configuration from collection collection1, trusted=true
[junit4] 2> 714286 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46391.solr.core.collection1.shard2.replica_n2' (registry
'solr.core.collection1.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 714286 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 714286 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node4/collection1_shard2_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node4/./collection1_shard2_replica_n2/data/]
[junit4] 2> 714316 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 714316 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 714317 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 714317 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 714318 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2a666760[collection1_shard1_replica_n1] main]
[junit4] 2> 714319 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 714319 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 714320 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 714320 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583740944694378496
[junit4] 2> 714320 INFO
(searcherExecutor-3261-thread-1-processing-n:127.0.0.1:41927_solr
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3)
[n:127.0.0.1:41927_solr c:collection1 s:shard1 r:core_node3
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] Registered new searcher
Searcher@2a666760[collection1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 714323 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 714323 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 714323 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 714324 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:41927/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 714335 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 714335 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 714337 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 714337 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 714338 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3d8988cc[collection1_shard2_replica_n2] main]
[junit4] 2> 714339 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 714339 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 714340 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 714341 INFO
(searcherExecutor-3262-thread-1-processing-n:127.0.0.1:46391_solr
x:collection1_shard2_replica_n2 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:46391_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_shard2_replica_n2] Registered new searcher
Searcher@3d8988cc[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 714341 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583740944716398592
[junit4] 2> 714344 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 714344 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 714344 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 714346 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:46391/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 714446 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714446 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714447 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714447 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714475 WARN (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.ZkController Skipping recovery according to sys prop
solrcloud.skip.autorecovery
[junit4] 2> 714486 INFO (qtp30265189-14624) [n:127.0.0.1:41927_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1235
[junit4] 2> 714496 WARN (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.c.ZkController Skipping recovery according to sys prop
solrcloud.skip.autorecovery
[junit4] 2> 714504 INFO (qtp2109450197-14621) [n:127.0.0.1:46391_solr
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1246
[junit4] 2> 714506 INFO (qtp1816923574-14639) [n:127.0.0.1:35615_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> 714600 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714600 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714600 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714600 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 714911 INFO
(OverseerCollectionConfigSetProcessor-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 715507 INFO (qtp1816923574-14639) [n:127.0.0.1:35615_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=2598
[junit4] 2> 715508 INFO (qtp1816923574-14640) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 715510 INFO
(OverseerThreadFactory-3248-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:35615_solr for creating new replica
[junit4] 2> 715511 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35615/solr";,
[junit4] 2> "node_name":"127.0.0.1:35615_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 715612 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715612 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715612 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715612 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715714 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=collection1_shard1_replica_n5&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 715714 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 715720 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 715724 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.s.IndexSchema [collection1_shard1_replica_n5] Schema name=minimal
[junit4] 2> 715726 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 715726 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n5' using
configuration from collection collection1, trusted=true
[junit4] 2> 715726 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_35615.solr.core.collection1.shard1.replica_n5' (registry
'solr.core.collection1.shard1.replica_n5') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 715726 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 715726 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.c.SolrCore [[collection1_shard1_replica_n5] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node3/collection1_shard1_replica_n5],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node3/./collection1_shard1_replica_n5/data/]
[junit4] 2> 715754 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 715754 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 715755 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 715755 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 715756 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1f84b756[collection1_shard1_replica_n5] main]
[junit4] 2> 715757 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 715757 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 715758 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 715758 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583740946202230784
[junit4] 2> 715758 INFO
(searcherExecutor-3271-thread-1-processing-n:127.0.0.1:35615_solr
x:collection1_shard1_replica_n5 s:shard1 c:collection1 r:core_node6)
[n:127.0.0.1:35615_solr c:collection1 s:shard1 r:core_node6
x:collection1_shard1_replica_n5] o.a.s.c.SolrCore
[collection1_shard1_replica_n5] Registered new searcher
Searcher@1f84b756[collection1_shard1_replica_n5]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 715760 WARN (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.c.ZkController Skipping recovery according to sys prop
solrcloud.skip.autorecovery
[junit4] 2> 715769 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
c:collection1 s:shard1 r:core_node6 x:collection1_shard1_replica_n5]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&name=collection1_shard1_replica_n5&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=54
[junit4] 2> 715770 INFO (qtp1816923574-14640) [n:127.0.0.1:35615_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=262
[junit4] 2> 715770 INFO (qtp1816923574-14635) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 715772 INFO
(OverseerCollectionConfigSetProcessor-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 715772 INFO
(OverseerThreadFactory-3248-thread-3-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:33045_solr for creating new replica
[junit4] 2> 715773 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:33045/solr";,
[junit4] 2> "node_name":"127.0.0.1:33045_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 715874 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715874 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715874 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715874 INFO
(zkCallback-2812-thread-1-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715874 INFO
(zkCallback-2812-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 715874 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716001 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&name=collection1_shard1_replica_n7&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 716001 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 716008 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 716013 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.s.IndexSchema [collection1_shard1_replica_n7] Schema name=minimal
[junit4] 2> 716014 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 716014 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n7' using
configuration from collection collection1, trusted=true
[junit4] 2> 716015 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33045.solr.core.collection1.shard1.replica_n7' (registry
'solr.core.collection1.shard1.replica_n7') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 716015 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 716015 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.c.SolrCore [[collection1_shard1_replica_n7] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node1/collection1_shard1_replica_n7],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node1/./collection1_shard1_replica_n7/data/]
[junit4] 2> 716048 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 716048 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 716048 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 716049 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 716050 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2aec9d32[collection1_shard1_replica_n7] main]
[junit4] 2> 716050 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 716050 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 716051 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 716052 INFO
(searcherExecutor-3276-thread-1-processing-n:127.0.0.1:33045_solr
x:collection1_shard1_replica_n7 s:shard1 c:collection1 r:core_node8)
[n:127.0.0.1:33045_solr c:collection1 s:shard1 r:core_node8
x:collection1_shard1_replica_n7] o.a.s.c.SolrCore
[collection1_shard1_replica_n7] Registered new searcher
Searcher@2aec9d32[collection1_shard1_replica_n7]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 716052 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583740946510512128
[junit4] 2> 716054 WARN (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.c.ZkController Skipping recovery according to sys prop
solrcloud.skip.autorecovery
[junit4] 2> 716061 INFO (qtp1282268795-14626) [n:127.0.0.1:33045_solr
c:collection1 s:shard1 r:core_node8 x:collection1_shard1_replica_n7]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&name=collection1_shard1_replica_n7&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=60
[junit4] 2> 716063 INFO (qtp1816923574-14635) [n:127.0.0.1:35615_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=292
[junit4] 2> 716064 INFO (qtp1816923574-14639) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 716065 INFO
(OverseerCollectionConfigSetProcessor-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 716066 INFO
(OverseerThreadFactory-3248-thread-4-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:33973_solr for creating new replica
[junit4] 2> 716068 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n9",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:33973/solr";,
[junit4] 2> "node_name":"127.0.0.1:33973_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 716169 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716169 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716170 INFO
(zkCallback-2806-thread-1-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716169 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716169 INFO
(zkCallback-2812-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716169 INFO
(zkCallback-2812-thread-3-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716169 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716170 INFO
(zkCallback-2806-thread-2-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716311 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&name=collection1_shard1_replica_n9&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 716311 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 716319 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 716323 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.s.IndexSchema [collection1_shard1_replica_n9] Schema name=minimal
[junit4] 2> 716324 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 716324 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n9' using
configuration from collection collection1, trusted=true
[junit4] 2> 716325 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33973.solr.core.collection1.shard1.replica_n9' (registry
'solr.core.collection1.shard1.replica_n9') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@74af335e
[junit4] 2> 716325 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 716325 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.c.SolrCore [[collection1_shard1_replica_n9] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node5/collection1_shard1_replica_n9],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderElectionIntegrationTest_DA31CFF668403EA6-001/tempDir-001/node5/./collection1_shard1_replica_n9/data/]
[junit4] 2> 716359 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 716359 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 716360 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 716360 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 716361 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1dc39588[collection1_shard1_replica_n9] main]
[junit4] 2> 716361 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 716361 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 716362 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 716363 INFO
(searcherExecutor-3281-thread-1-processing-n:127.0.0.1:33973_solr
x:collection1_shard1_replica_n9 s:shard1 c:collection1 r:core_node10)
[n:127.0.0.1:33973_solr c:collection1 s:shard1 r:core_node10
x:collection1_shard1_replica_n9] o.a.s.c.SolrCore
[collection1_shard1_replica_n9] Registered new searcher
Searcher@1dc39588[collection1_shard1_replica_n9]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 716363 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583740946836619264
[junit4] 2> 716366 WARN (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.c.ZkController Skipping recovery according to sys prop
solrcloud.skip.autorecovery
[junit4] 2> 716373 INFO (qtp1138415523-14633) [n:127.0.0.1:33973_solr
c:collection1 s:shard1 r:core_node10 x:collection1_shard1_replica_n9]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&name=collection1_shard1_replica_n9&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=62
[junit4] 2> 716375 INFO (qtp1816923574-14639) [n:127.0.0.1:35615_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=311
[junit4] 2> 716376 INFO (qtp1816923574-14637) [n:127.0.0.1:35615_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 716377 INFO
(OverseerCollectionConfigSetProcessor-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 716377 INFO
(OverseerThreadFactory-3248-thread-5-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:34829_solr for creating new replica
[junit4] 2> 716379 INFO
(OverseerStateUpdate-98983808903086097-127.0.0.1:35615_solr-n_0000000000)
[n:127.0.0.1:35615_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n11",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34829/solr";,
[junit4] 2> "node_name":"127.0.0.1:34829_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 716481 INFO
(zkCallback-2808-thread-2-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2806-thread-2-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2807-thread-1-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2806-thread-1-processing-n:127.0.0.1:33045_solr)
[n:127.0.0.1:33045_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2812-thread-2-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2803-thread-1-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2803-thread-2-processing-n:127.0.0.1:46391_solr)
[n:127.0.0.1:46391_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2812-thread-3-processing-n:127.0.0.1:35615_solr)
[n:127.0.0.1:35615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2807-thread-2-processing-n:127.0.0.1:33973_solr)
[n:127.0.0.1:33973_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716481 INFO
(zkCallback-2808-thread-1-processing-n:127.0.0.1:41927_solr)
[n:127.0.0.1:41927_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 716607 INFO (qtp8592178-14665) [n:127.0.0.1:34829_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&name=collection1_shard1_replica_n11&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 716607 INFO (qtp8592178-14665) [n:127.0.0.1:34829_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 716617 INFO (qtp8592178-14665) [n:127.0.0.1:34829_solr
c:collection1 s:shard1 r:core_node12 x:collection1_shard1_replica_n11]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 716623 INFO (qtp8592178-14665) [n:127.0.0.1:34829_solr
c:collection1 s:shard1 r:core_node12 x:collection1_shard1_replica_n11]
o.a.s.s.IndexSch
[...truncated too long message...]
in = solr.node, service url = null, agent id = null] for registry solr.node /
com.codahale.metrics.MetricRegistry@3e52f00c
[junit4] 2> 75012 INFO (jetty-closer-32-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 75012 INFO (jetty-closer-32-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3992da95: rootName =
solr_37383, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@339dcdd8
[junit4] 2> 75017 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 75017 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2ece32bb: rootName =
solr_43723, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@36074e6e
[junit4] 2> 75022 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 75022 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@22178485: rootName =
solr_43723, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@339dcdd8
[junit4] 2> 75023 INFO (jetty-closer-32-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:39009_solr
[junit4] 2> 75024 INFO (jetty-closer-32-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37383_solr
[junit4] 2> 75024 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:43723_solr
[junit4] 2> 75025 INFO (jetty-closer-32-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 75034 INFO
(zkCallback-48-thread-3-processing-n:127.0.0.1:39009_solr)
[n:127.0.0.1:39009_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (0)
[junit4] 2> 75034 INFO (jetty-closer-32-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 75035 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 75035 INFO (jetty-closer-32-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 75035 INFO
(zkCallback-47-thread-4-processing-n:127.0.0.1:37383_solr)
[n:127.0.0.1:37383_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (0)
[junit4] 2> 75035 INFO
(zkCallback-46-thread-2-processing-n:127.0.0.1:43723_solr)
[n:127.0.0.1:43723_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (0)
[junit4] 2> 75064 INFO (coreCloseExecutor-320-thread-1)
[n:127.0.0.1:39009_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5c53573c
[junit4] 2> 75064 INFO (coreCloseExecutor-320-thread-1)
[n:127.0.0.1:39009_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard1.replica_n1, tag=1548965692
[junit4] 2> 75065 INFO (coreCloseExecutor-320-thread-1)
[n:127.0.0.1:39009_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@456e7bc4: rootName =
solr_39009, domain = solr.core.collection1.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.collection1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@1780229c
[junit4] 2> 75066 INFO (jetty-closer-32-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@385ec937{/solr,null,UNAVAILABLE}
[junit4] 2> 75066 INFO (coreCloseExecutor-321-thread-1)
[n:127.0.0.1:37383_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n3] o.a.s.c.SolrCore
[collection1_shard2_replica_n3] CLOSING SolrCore
org.apache.solr.core.SolrCore@5e366316
[junit4] 2> 75066 INFO (coreCloseExecutor-321-thread-1)
[n:127.0.0.1:37383_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard2.replica_n3, tag=1580622614
[junit4] 2> 75067 INFO (coreCloseExecutor-321-thread-1)
[n:127.0.0.1:37383_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@54ec170b: rootName =
solr_37383, domain = solr.core.collection1.shard2.replica_n3, service url =
null, agent id = null] for registry solr.core.collection1.shard2.replica_n3 /
com.codahale.metrics.MetricRegistry@749f8d22
[junit4] 2> 75089 INFO (coreCloseExecutor-320-thread-1)
[n:127.0.0.1:39009_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=1548965692
[junit4] 2> 75103 INFO (coreCloseExecutor-321-thread-1)
[n:127.0.0.1:37383_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=1580622614
[junit4] 2> 75113 INFO (jetty-closer-32-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@64013904{/solr,null,UNAVAILABLE}
[junit4] 2> 75116 INFO (jetty-closer-32-thread-3) [ ] o.a.s.c.Overseer
Overseer (id=98983933229137929-127.0.0.1:37383_solr-n_0000000000) closing
[junit4] 2> 75116 INFO
(OverseerStateUpdate-98983933229137929-127.0.0.1:37383_solr-n_0000000000)
[n:127.0.0.1:37383_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:37383_solr
[junit4] 2> 75117 WARN
(OverseerAutoScalingTriggerThread-98983933229137929-127.0.0.1:37383_solr-n_0000000000)
[n:127.0.0.1:37383_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 75119 INFO (jetty-closer-32-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@48deaf6b{/solr,null,UNAVAILABLE}
[junit4] 2> 75121 ERROR
(SUITE-CloudSolrClientTest-seed#[C7150671B82F8B1C]-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> 75122 INFO
(SUITE-CloudSolrClientTest-seed#[C7150671B82F8B1C]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43785 43785
[junit4] 2> 80153 INFO (Thread-13) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:43785 43785
[junit4] 2> 80153 WARN (Thread-13) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/collection1/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 2
/solr/overseer_elect/election/98983933229137929-127.0.0.1:37383_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2>
[junit4] 2> 80154 INFO
(SUITE-CloudSolrClientTest-seed#[C7150671B82F8B1C]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1123, maxMBSortInHeap=7.946210943354128,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4e6d9db6),
locale=ja, timezone=America/Fortaleza
[junit4] 2> NOTE: Linux 4.10.0-37-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=8,threads=1,free=383696352,total=519569408
[junit4] 2> NOTE: All tests run in this JVM:
[StreamExpressionToExplanationTest, NormalizeEvaluatorTest,
ArcCosineEvaluatorTest, TestCoreAdmin, CosineEvaluatorTest, TestJavaBinCodec,
TestHash, CloudSolrClientBuilderTest, PowerEvaluatorTest, JdbcDriverTest,
TestSuggesterResponse, ArrayEvaluatorTest, CloudSolrClientTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudSolrClientTest
-Dtests.seed=C7150671B82F8B1C -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=ja -Dtests.timezone=America/Fortaleza -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | CloudSolrClientTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 30 seconds
[junit4] > at
__randomizedtesting.SeedInfo.seed([C7150671B82F8B1C]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.setupCluster(CloudSolrClientTest.java:104)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [64/146 (1!)] on J2 in 69.30s, 0 tests, 1 failure <<<
FAILURES!
[...truncated 46540 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
