Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2170/
3 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Expected 2 of 3 replicas to be active but only found 1;
[core_node5:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43419","node_name":"127.0.0.1:43419_","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/28)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node4":{ "core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:42650";, "node_name":"127.0.0.1:42650_",
"state":"down", "type":"NRT"}, "core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43419";, "node_name":"127.0.0.1:43419_",
"state":"active", "type":"NRT", "leader":"true"},
"core_node6":{ "state":"down",
"base_url":"http://127.0.0.1:46239";,
"core":"c8n_1x3_lf_shard1_replica_n3",
"node_name":"127.0.0.1:46239_", "type":"NRT"}}}},
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Expected 2 of 3 replicas to be active but only found
1;
[core_node5:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43419","node_name":"127.0.0.1:43419_","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/28)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node4":{
"core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:42650";,
"node_name":"127.0.0.1:42650_",
"state":"down",
"type":"NRT"},
"core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43419";,
"node_name":"127.0.0.1:43419_",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node6":{
"state":"down",
"base_url":"http://127.0.0.1:46239";,
"core":"c8n_1x3_lf_shard1_replica_n3",
"node_name":"127.0.0.1:46239_",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([DC7D1AB85D1EFA65:54292562F3E2979D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:169)
at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:56)
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
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at
__randomizedtesting.SeedInfo.seed([DC7D1AB85D1EFA65:8B54E10D86EC187E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:709)
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)
FAILED:
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale
Error Message:
Error from server at
https://127.0.0.1:41780/solr/stale_state_test_col_shard1_replica_n1: Expected
mime type application/octet-stream but got text/html. <html> <head> <meta
http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error
404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing
/solr/stale_state_test_col_shard1_replica_n1/update. Reason: <pre> Can not
find: /solr/stale_state_test_col_shard1_replica_n1/update</pre></p> <hr /><a
href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.20.v20170531</a><hr/>
</body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at
https://127.0.0.1:41780/solr/stale_state_test_col_shard1_replica_n1: Expected
mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 </title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /solr/stale_state_test_col_shard1_replica_n1/update.
Reason:
<pre> Can not find:
/solr/stale_state_test_col_shard1_replica_n1/update</pre></p>
<hr /><a href="http://eclipse.org/jetty";>Powered by Jetty://
9.3.20.v20170531</a><hr/>
</body>
</html>
at
__randomizedtesting.SeedInfo.seed([18CE6A16DE84BCBD:ACFFF2FE3D6DCA91]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:607)
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.directUpdate(CloudSolrClient.java:559)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1016)
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.request.UpdateRequest.commit(UpdateRequest.java:233)
at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testRetryUpdatesWhenClusterStateIsStale(CloudSolrClientTest.java:844)
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
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
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 13391 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[junit4] 2> 4844421 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[DC7D1AB85D1EFA65]-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/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/init-core-data-001
[junit4] 2> 4844421 WARN
(SUITE-LeaderFailoverAfterPartitionTest-seed#[DC7D1AB85D1EFA65]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 4844482 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[DC7D1AB85D1EFA65]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4844496 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[DC7D1AB85D1EFA65]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 4844496 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[DC7D1AB85D1EFA65]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 4844517 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4844517 INFO (Thread-4499) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4844517 INFO (Thread-4499) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 4844526 ERROR (Thread-4499) [ ] 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> 4844622 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkTestServer start zk server on port:43561
[junit4] 2> 4844695 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4844721 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 4844723 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4844736 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4844737 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4844738 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4844753 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4844754 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4844756 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4844782 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4844783 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4844785 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 4845276 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 4845365 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@53a04c8{/,null,AVAILABLE}
[junit4] 2> 4845365 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@947737{HTTP/1.1,[http/1.1]}{127.0.0.1:39343}
[junit4] 2> 4845365 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server Started @4858569ms
[junit4] 2> 4845365 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/tempDir-001/control/data,
replicaType=NRT, hostContext=/, hostPort=38174,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/control-001/cores}
[junit4] 2> 4845366 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4845366 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 4845366 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4845366 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4845366 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-12T04:29:44.433Z
[junit4] 2> 4845419 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4845419 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/control-001/solr.xml
[junit4] 2> 4845422 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4845422 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4845436 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4845438 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43561/solr
[junit4] 2> 4846224 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4846224 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:38174_
[junit4] 2> 4846225 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.c.Overseer Overseer
(id=98989571020292100-127.0.0.1:38174_-n_0000000000) starting
[junit4] 2> 4846387 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38174_
[junit4] 2> 4846425 INFO
(zkCallback-2994-thread-1-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4846680 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4846772 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4846772 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4846822 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:38174_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/control-001/cores
[junit4] 2> 4847055 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4847055 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43561/solr ready
[junit4] 2> 4847061 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=58114,localport=38174], receiveBufferSize:531000
[junit4] 2> 4847078 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39343,localport=54164], receiveBufferSize=530904
[junit4] 2> 4847171 INFO (qtp1102285128-19157) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38174_&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4847188 INFO
(OverseerThreadFactory-6961-thread-1-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.CreateCollectionCmd Create collection
control_collection
[junit4] 2> 4847337 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=58124,localport=38174], receiveBufferSize:531000
[junit4] 2> 4847350 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39343,localport=54174], receiveBufferSize=530904
[junit4] 2> 4847350 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4847351 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4847623 INFO
(zkCallback-2994-thread-1-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4848797 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4848960 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 4850260 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4851026 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 4851026 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4851026 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4851026 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 4851081 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.25050044036681324]
[junit4] 2> 4851110 WARN (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 4851715 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 4851715 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4851716 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4851716 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4851732 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1661407147768431]
[junit4] 2> 4851733 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@25259605[control_collection_shard1_replica_n1] main]
[junit4] 2> 4851734 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4851734 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4851767 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4851768 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1583833143925800960
[junit4] 2> 4851773 INFO
(searcherExecutor-6964-thread-1-processing-n:127.0.0.1:38174_
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection)
[n:127.0.0.1:38174_ c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@25259605[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:38174/control_collection_shard1_replica_n1/
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:38174/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 4851790 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4851804 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:38174/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 4851825 INFO
(zkCallback-2994-thread-2-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4851861 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4851862 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=4511
[junit4] 2> 4851898 INFO (qtp1102285128-19157) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 4851899 INFO
(OverseerCollectionConfigSetProcessor-98989571020292100-127.0.0.1:38174_-n_0000000000)
[n:127.0.0.1:38174_ ] 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> 4852027 INFO
(zkCallback-2994-thread-2-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4852910 INFO (qtp1102285128-19157) [n:127.0.0.1:38174_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38174_&wt=javabin&version=2}
status=0 QTime=5738
[junit4] 2> 4853004 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4853020 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43561/solr ready
[junit4] 2> 4853020 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 4853020 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=58154,localport=38174], receiveBufferSize:531000
[junit4] 2> 4853040 INFO (qtp1102285128-19158) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4853077 INFO (SocketProxy-Acceptor-38174) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39343,localport=54204], receiveBufferSize=530904
[junit4] 2> 4853113 INFO
(OverseerThreadFactory-6961-thread-2-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 4853115 WARN
(OverseerThreadFactory-6961-thread-2-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.CreateCollectionCmd It is unusual to create a
collection (collection1) without cores.
[junit4] 2> 4853480 INFO (qtp1102285128-19158) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 4853480 INFO (qtp1102285128-19158) [n:127.0.0.1:38174_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2}
status=0 QTime=439
[junit4] 2> 4853958 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001
of type NRT
[junit4] 2> 4853959 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 4853959 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5294663b{/,null,AVAILABLE}
[junit4] 2> 4854155 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@28790d9b{HTTP/1.1,[http/1.1]}{127.0.0.1:36866}
[junit4] 2> 4854155 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server Started @4867359ms
[junit4] 2> 4854155 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/tempDir-001/jetty1,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=43419,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001/cores}
[junit4] 2> 4854155 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4854156 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 4854156 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4854156 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4854156 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-12T04:29:53.223Z
[junit4] 2> 4854240 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4854240 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001/solr.xml
[junit4] 2> 4854244 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4854244 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4854439 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4854508 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43561/solr
[junit4] 2> 4854601 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 4854602 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4854620 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43419_
[junit4] 2> 4854640 INFO (zkCallback-3002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4854640 INFO
(zkCallback-3007-thread-1-processing-n:127.0.0.1:43419_) [n:127.0.0.1:43419_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4854640 INFO
(zkCallback-2994-thread-1-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4854928 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4855055 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4855055 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4855056 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:43419_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001/cores
[junit4] 2> 4855119 INFO
(OverseerCollectionConfigSetProcessor-98989571020292100-127.0.0.1:38174_-n_0000000000)
[n:127.0.0.1:38174_ ] 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> 4855341 INFO (qtp1102285128-19154) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:43419_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4855396 INFO
(OverseerThreadFactory-6961-thread-3-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:43419_
for creating new replica
[junit4] 2> 4855399 INFO (SocketProxy-Acceptor-43419) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=37178,localport=43419], receiveBufferSize:531000
[junit4] 2> 4855412 INFO (SocketProxy-Acceptor-43419) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36866,localport=53916], receiveBufferSize=530904
[junit4] 2> 4855459 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4855459 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4856533 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4856605 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
[collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 4857251 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4857293 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n41' using configuration from
collection collection1, trusted=true
[junit4] 2> 4857294 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n41' (registry
'solr.core.collection1.shard2.replica_n41') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4857294 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4857294 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[[collection1_shard2_replica_n41] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001/cores/collection1_shard2_replica_n41],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-1-001/cores/collection1_shard2_replica_n41/data/]
[junit4] 2> 4857312 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.25050044036681324]
[junit4] 2> 4857316 WARN (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 4857477 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4857477 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4857478 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 4857479 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 4857479 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1661407147768431]
[junit4] 2> 4857480 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@789ad8de[collection1_shard2_replica_n41] main]
[junit4] 2> 4857498 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4857498 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4857498 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4857499 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1583833149935190016
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:43419/collection1_shard2_replica_n41/
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy
http://127.0.0.1:43419/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 4857502 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4857504 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43419/collection1_shard2_replica_n41/ shard2
[junit4] 2> 4857510 INFO
(searcherExecutor-6975-thread-1-processing-n:127.0.0.1:43419_
x:collection1_shard2_replica_n41 s:shard2 c:collection1) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore
[collection1_shard2_replica_n41] Registered new searcher
Searcher@789ad8de[collection1_shard2_replica_n41]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4857554 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 4857556 INFO (qtp1097035661-19211) [n:127.0.0.1:43419_
c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2096
[junit4] 2> 4857568 INFO (qtp1102285128-19154) [n:127.0.0.1:38174_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43419_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=2226
[junit4] 2> 4858094 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001
of type NRT
[junit4] 2> 4858094 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 4858095 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@48559bd7{/,null,AVAILABLE}
[junit4] 2> 4858095 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@959b6dd{HTTP/1.1,[http/1.1]}{127.0.0.1:42423}
[junit4] 2> 4858095 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server Started @4871299ms
[junit4] 2> 4858130 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/tempDir-001/jetty2,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=42650,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001/cores}
[junit4] 2> 4858130 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4858130 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 4858130 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4858130 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4858131 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-12T04:29:57.198Z
[junit4] 2> 4858151 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4858151 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001/solr.xml
[junit4] 2> 4858183 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4858183 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4858184 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4858198 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43561/solr
[junit4] 2> 4858220 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 4858221 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4858234 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42650_
[junit4] 2> 4858254 INFO
(zkCallback-3013-thread-1-processing-n:127.0.0.1:42650_) [n:127.0.0.1:42650_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4858262 INFO (zkCallback-3002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4858262 INFO
(zkCallback-2994-thread-2-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4858263 INFO
(zkCallback-3007-thread-1-processing-n:127.0.0.1:43419_) [n:127.0.0.1:43419_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4858519 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4858549 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4858549 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4858550 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:42650_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001/cores
[junit4] 2> 4858648 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:42650_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4858662 INFO
(OverseerCollectionConfigSetProcessor-98989571020292100-127.0.0.1:38174_-n_0000000000)
[n:127.0.0.1:38174_ ] 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> 4858663 INFO
(OverseerThreadFactory-6961-thread-4-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:42650_
for creating new replica
[junit4] 2> 4858694 INFO (SocketProxy-Acceptor-42650) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=58510,localport=42650], receiveBufferSize:531000
[junit4] 2> 4858695 INFO (SocketProxy-Acceptor-42650) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=42423,localport=41566], receiveBufferSize=530904
[junit4] 2> 4858714 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4858714 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4859866 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4859909 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema
[collection1_shard1_replica_n43] Schema name=test
[junit4] 2> 4860368 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4860420 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n43' using configuration from
collection collection1, trusted=true
[junit4] 2> 4860420 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n43' (registry
'solr.core.collection1.shard1.replica_n43') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4860420 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4860420 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
[[collection1_shard1_replica_n43] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001/cores/collection1_shard1_replica_n43],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-2-001/cores/collection1_shard1_replica_n43/data/]
[junit4] 2> 4860439 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.25050044036681324]
[junit4] 2> 4860455 WARN (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 4860644 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4860644 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4860661 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 4860661 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 4860662 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1661407147768431]
[junit4] 2> 4860662 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4605fc07[collection1_shard1_replica_n43] main]
[junit4] 2> 4860663 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4860664 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4860664 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4860665 INFO
(searcherExecutor-6986-thread-1-processing-n:127.0.0.1:42650_
x:collection1_shard1_replica_n43 s:shard1 c:collection1) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore
[collection1_shard1_replica_n43] Registered new searcher
Searcher@4605fc07[collection1_shard1_replica_n43]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4860677 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1583833153267564544
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:42650/collection1_shard1_replica_n43/
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy
http://127.0.0.1:42650/collection1_shard1_replica_n43/ has no replicas
[junit4] 2> 4860681 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 4860695 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42650/collection1_shard1_replica_n43/ shard1
[junit4] 2> 4860852 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 4860854 INFO (qtp1023547926-19244) [n:127.0.0.1:42650_
c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2139
[junit4] 2> 4860856 INFO (qtp1102285128-19155) [n:127.0.0.1:38174_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:42650_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=2207
[junit4] 2> 4861273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001
of type NRT
[junit4] 2> 4861274 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 4861287 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3b613b62{/,null,AVAILABLE}
[junit4] 2> 4861287 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@35616fe9{HTTP/1.1,[http/1.1]}{127.0.0.1:35651}
[junit4] 2> 4861287 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.e.j.s.Server Started @4874492ms
[junit4] 2> 4861287 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/, hostPort=46239,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001/cores}
[junit4] 2> 4861288 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4861288 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 4861288 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4861288 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4861288 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-12T04:30:00.355Z
[junit4] 2> 4861328 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4861328 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001/solr.xml
[junit4] 2> 4861331 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 4861331 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4861332 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 4861334 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43561/solr
[junit4] 2> 4861372 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 4861373 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4861374 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46239_
[junit4] 2> 4861388 INFO
(zkCallback-3019-thread-1-processing-n:127.0.0.1:46239_) [n:127.0.0.1:46239_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4861388 INFO (zkCallback-3002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4861392 INFO
(zkCallback-3013-thread-1-processing-n:127.0.0.1:42650_) [n:127.0.0.1:42650_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4861394 INFO
(zkCallback-3007-thread-1-processing-n:127.0.0.1:43419_) [n:127.0.0.1:43419_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4861394 INFO
(zkCallback-2994-thread-1-processing-n:127.0.0.1:38174_) [n:127.0.0.1:38174_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4861578 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4861630 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4861631 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4861649 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[DC7D1AB85D1EFA65])
[n:127.0.0.1:46239_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001/cores
[junit4] 2> 4861798 INFO (qtp1102285128-19157) [n:127.0.0.1:38174_ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:46239_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 4861812 INFO
(OverseerCollectionConfigSetProcessor-98989571020292100-127.0.0.1:38174_-n_0000000000)
[n:127.0.0.1:38174_ ] 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> 4861812 INFO
(OverseerThreadFactory-6961-thread-5-processing-n:127.0.0.1:38174_)
[n:127.0.0.1:38174_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:46239_
for creating new replica
[junit4] 2> 4861814 INFO (SocketProxy-Acceptor-46239) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=57772,localport=46239], receiveBufferSize:531000
[junit4] 2> 4861814 INFO (SocketProxy-Acceptor-46239) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=35651,localport=58876], receiveBufferSize=530904
[junit4] 2> 4861814 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n45&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4861817 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4862860 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4862902 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema
[collection1_shard2_replica_n45] Schema name=test
[junit4] 2> 4863417 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4863474 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n45' using configuration from
collection collection1, trusted=true
[junit4] 2> 4863475 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n45' (registry
'solr.core.collection1.shard2.replica_n45') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@70402c79
[junit4] 2> 4863475 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4863475 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore
[[collection1_shard2_replica_n45] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001/cores/collection1_shard2_replica_n45],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_DC7D1AB85D1EFA65-001/shard-3-001/cores/collection1_shard2_replica_n45/data/]
[junit4] 2> 4863477 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.25050044036681324]
[junit4] 2> 4863493 WARN (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 4863697 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4863697 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4863698 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 4863698 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 4863711 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1661407147768431]
[junit4] 2> 4863711 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5fdc7882[collection1_shard2_replica_n45] main]
[junit4] 2> 4863712 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4863712 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4863713 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4863714 INFO
(searcherExecutor-6997-thread-1-processing-n:127.0.0.1:46239_
x:collection1_shard2_replica_n45 s:shard2 c:collection1) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore
[collection1_shard2_replica_n45] Registered new searcher
Searcher@5fdc7882[collection1_shard2_replica_n45]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4863714 INFO (qtp1404885229-19273) [n:127.0.0.1:46239_
c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1583833156452089856
[junit4] 2> 4863729
[...truncated too long message...]
[junit4] 2> 512715 INFO (coreCloseExecutor-703-thread-4)
[n:127.0.0.1:33093_solr c:localShardsTestColl s:shard2 r:core_node9
x:localShardsTestColl_shard2_replica_n6] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.localShardsTestColl.shard2.leader, tag=416236750
[junit4] 2> 512767 INFO (coreCloseExecutor-704-thread-8)
[n:127.0.0.1:37682_solr c:multicollection2 s:shard1 r:core_node3
x:multicollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.multicollection2.shard1.replica_n1,
tag=730908141
[junit4] 2> 512767 INFO (coreCloseExecutor-704-thread-8)
[n:127.0.0.1:37682_solr c:multicollection2 s:shard1 r:core_node3
x:multicollection2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@63f459f1: rootName
= solr_37682, domain = solr.core.multicollection2.shard1.replica_n1, service
url = null, agent id = null] for registry
solr.core.multicollection2.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@73d4f91c
[junit4] 2> 512791 INFO (coreCloseExecutor-703-thread-5)
[n:127.0.0.1:33093_solr c:2nd_collection s:shard2 r:core_node4
x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.2nd_collection.shard2.replica_n2,
tag=1727835702
[junit4] 2> 512791 INFO (coreCloseExecutor-703-thread-5)
[n:127.0.0.1:33093_solr c:2nd_collection s:shard2 r:core_node4
x:2nd_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5d6eb95a: rootName =
solr_33093, domain = solr.core.2nd_collection.shard2.replica_n2, service url =
null, agent id = null] for registry solr.core.2nd_collection.shard2.replica_n2
/ com.codahale.metrics.MetricRegistry@1b2a550b
[junit4] 2> 513273 INFO (coreCloseExecutor-704-thread-8)
[n:127.0.0.1:37682_solr c:multicollection2 s:shard1 r:core_node3
x:multicollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.multicollection2.shard1.leader,
tag=730908141
[junit4] 2> 513292 INFO (coreCloseExecutor-704-thread-2)
[n:127.0.0.1:37682_solr c:localShardsTestColl s:shard2 r:core_node13
x:localShardsTestColl_shard2_replica_n10] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.localShardsTestColl.shard2.leader, tag=1660262768
[junit4] 2> 513295 INFO (coreCloseExecutor-703-thread-6)
[n:127.0.0.1:33093_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.foo.shard1.replica_n2, tag=1846337072
[junit4] 2> 513296 INFO (coreCloseExecutor-703-thread-6)
[n:127.0.0.1:33093_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2f813db8: rootName =
solr_33093, domain = solr.core.foo.shard1.replica_n2, service url = null, agent
id = null] for registry solr.core.foo.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@31805347
[junit4] 2> 513461 INFO (coreCloseExecutor-704-thread-5)
[n:127.0.0.1:37682_solr c:stale_state_test_col s:shard1 r:core_node4
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.stale_state_test_col.shard1.leader, tag=951556701
[junit4] 2> 513644 INFO (coreCloseExecutor-703-thread-6)
[n:127.0.0.1:33093_solr c:foo s:shard1 r:core_node5 x:foo_shard1_replica_n2]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.foo.shard1.leader, tag=1846337072
[junit4] 2> 513644 INFO (coreCloseExecutor-703-thread-7)
[n:127.0.0.1:33093_solr c:multicollection2 s:shard2 r:core_node4
x:multicollection2_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.multicollection2.shard2.replica_n2,
tag=607439588
[junit4] 2> 513644 INFO (coreCloseExecutor-703-thread-7)
[n:127.0.0.1:33093_solr c:multicollection2 s:shard2 r:core_node4
x:multicollection2_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3e3aa52b: rootName
= solr_33093, domain = solr.core.multicollection2.shard2.replica_n2, service
url = null, agent id = null] for registry
solr.core.multicollection2.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@3685cfb7
[junit4] 2> 513867 INFO (coreCloseExecutor-703-thread-5)
[n:127.0.0.1:33093_solr c:2nd_collection s:shard2 r:core_node4
x:2nd_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.2nd_collection.shard2.leader,
tag=1727835702
[junit4] 2> 513868 INFO (coreCloseExecutor-703-thread-7)
[n:127.0.0.1:33093_solr c:multicollection2 s:shard2 r:core_node4
x:multicollection2_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.multicollection2.shard2.leader,
tag=607439588
[junit4] 2> 515156 INFO (jetty-closer-145-thread-3) [ ]
o.a.s.c.Overseer Overseer
(id=98989721210126342-127.0.0.1:33093_solr-n_0000000000) closing
[junit4] 2> 515167 INFO
(OverseerStateUpdate-98989721210126342-127.0.0.1:33093_solr-n_0000000000)
[n:127.0.0.1:33093_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33093_solr
[junit4] 2> 515232 INFO (jetty-closer-145-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1277b22b{/solr,null,UNAVAILABLE}
[junit4] 2> 515256 WARN
(OverseerAutoScalingTriggerThread-98989721210126342-127.0.0.1:33093_solr-n_0000000000)
[n:127.0.0.1:33093_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 515716 INFO (jetty-closer-145-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@291f08ca{/solr,null,UNAVAILABLE}
[junit4] 2> 516731 INFO (jetty-closer-145-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7b8bd3aa{/solr,null,UNAVAILABLE}
[junit4] 2> 516751 ERROR
(SUITE-CloudSolrClientTest-seed#[18CE6A16DE84BCBD]-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> 516752 INFO
(SUITE-CloudSolrClientTest-seed#[18CE6A16DE84BCBD]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37842 37842
[junit4] 2> 516860 INFO (Thread-215) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:37842 37842
[junit4] 2> 516877 WARN (Thread-215) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/collections/localShardsTestColl/state.json
[junit4] 2> 24 /solr/collections/foo/state.json
[junit4] 2> 18 /solr/collections/multicollection1/state.json
[junit4] 2> 17 /solr/collections/collection1/state.json
[junit4] 2> 16
/solr/collections/stale_state_test_col/state.json
[junit4] 2> 14 /solr/collections/2nd_collection/state.json
[junit4] 2> 14 /solr/collections/nemesis/state.json
[junit4] 2> 14 /solr/collections/multicollection2/state.json
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/overwrite/state.json
[junit4] 2> 2
/solr/collections/localShardsTestColl/leader_elect/shard3/election/98989721210126344-core_node17-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_18CE6A16DE84BCBD-001
[junit4] 2> Nov 12, 2017 5:09:28 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{multiDefault=PostingsFormat(name=Memory), a_t=PostingsFormat(name=Memory),
title_s=PostingsFormat(name=Memory), id=Lucene50(blocksize=128),
text=Lucene50(blocksize=128)},
docValues:{_version_=DocValuesFormat(name=Asserting),
multiDefault=DocValuesFormat(name=Lucene70),
title_s=DocValuesFormat(name=Lucene70),
intDefault=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Memory),
timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=602,
maxMBSortInHeap=6.674115762870473,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@ecf2c21),
locale=ja-JP, timezone=America/St_Barthelemy
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=297988672,total=447741952
[junit4] 2> NOTE: All tests run in this JVM: [SolrExampleXMLTest,
RegressionEvaluatorTest, LargeVolumeJettyTest, LessThanEqualToEvaluatorTest,
NoOpResponseParserTest, NamedListTest, TestValidatingJsonMap,
TestCollectionStateWatchers, DivideEvaluatorTest, GetByIdTest, TestCoreAdmin,
TestSuggesterResponse, SelectWithEvaluatorsTest, NormalizeEvaluatorTest,
LBHttpSolrClientBuilderTest, GreaterThanEqualToEvaluatorTest,
TestSolrJErrorHandling, CoalesceEvaluatorTest, UuidEvaluatorTest,
SolrParamTest, ArrayEvaluatorTest, CloudSolrClientBuilderTest,
HyperbolicSineEvaluatorTest, TestJsonRecordReader, JettyWebappTest,
CloudSolrClientTest]
[junit4] Completed [88/146 (1!)] on J2 in 94.15s, 16 tests, 1 error <<<
FAILURES!
[...truncated 46454 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
