Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/338/
Java: 32bit/jdk-9-ea+181 -server -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test
Error Message:
Expected 2 of 3 replicas to be active but only found 1;
[core_node4:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43535/jhy/rs","node_name":"127.0.0.1:43535_jhy%2Frs","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/29)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node4":{ "core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43535/jhy/rs";,
"node_name":"127.0.0.1:43535_jhy%2Frs", "state":"active",
"type":"NRT", "leader":"true"}, "core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:41367/jhy/rs";,
"node_name":"127.0.0.1:41367_jhy%2Frs", "state":"down",
"type":"NRT"}, "core_node6":{ "state":"down",
"base_url":"http://127.0.0.1:42955/jhy/rs";,
"core":"c8n_1x3_lf_shard1_replica_n3",
"node_name":"127.0.0.1:42955_jhy%2Frs", "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_node4:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43535/jhy/rs","node_name":"127.0.0.1:43535_jhy%2Frs","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/29)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node4":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43535/jhy/rs";,
"node_name":"127.0.0.1:43535_jhy%2Frs",
"state":"active",
"type":"NRT",
"leader":"true"},
"core_node5":{
"core":"c8n_1x3_lf_shard1_replica_n1",
"base_url":"http://127.0.0.1:41367/jhy/rs";,
"node_name":"127.0.0.1:41367_jhy%2Frs",
"state":"down",
"type":"NRT"},
"core_node6":{
"state":"down",
"base_url":"http://127.0.0.1:42955/jhy/rs";,
"core":"c8n_1x3_lf_shard1_replica_n3",
"node_name":"127.0.0.1:42955_jhy%2Frs",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([321FEF2686CFF28B:BA4BD0FC28339F73]: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
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12401 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[junit4] 2> 1068165 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[321FEF2686CFF28B]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/init-core-data-001
[junit4] 2> 1068166 WARN
(SUITE-LeaderFailoverAfterPartitionTest-seed#[321FEF2686CFF28B]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1068166 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[321FEF2686CFF28B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1068166 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[321FEF2686CFF28B]-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> 1068167 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[321FEF2686CFF28B]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /jhy/rs
[junit4] 2> 1068168 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1068168 INFO (Thread-3028) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1068169 INFO (Thread-3028) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1068170 ERROR (Thread-3028) [ ] 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> 1068269 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkTestServer start zk server on port:33527
[junit4] 2> 1068278 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1068279 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 1068281 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1068282 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1068283 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1068285 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1068286 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1068287 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1068289 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1068290 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1068291 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1068293 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 1068388 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1068389 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1537990{/jhy/rs,null,AVAILABLE}
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1178b1d{HTTP/1.1,[http/1.1]}{127.0.0.1:38055}
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server Started @1069714ms
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhy/rs,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/tempDir-001/control/data,
hostPort=42955,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores}
[junit4] 2> 1068390 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1068390 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T21:19:37.285742Z
[junit4] 2> 1068392 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e350386690002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1068392 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1068392 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/solr.xml
[junit4] 2> 1068394 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1068396 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33527/solr
[junit4] 2> 1068433 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1068433 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:42955_jhy%2Frs
[junit4] 2> 1068434 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.Overseer Overseer
(id=98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000) starting
[junit4] 2> 1068436 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42955_jhy%2Frs
[junit4] 2> 1068436 INFO (zkCallback-1446-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1068516 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1068524 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1068524 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1068525 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores
[junit4] 2> 1068536 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1068537 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33527/solr ready
[junit4] 2> 1068537 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=56866,localport=42955], receiveBufferSize:531000
[junit4] 2> 1068537 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=38055,localport=49468], receiveBufferSize=530904
[junit4] 2> 1068538 INFO (qtp9965701-9840) [ ]
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:42955_jhy%252Frs&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1068541 INFO (OverseerThreadFactory-4058-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1068645 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=56870,localport=42955], receiveBufferSize:531000
[junit4] 2> 1068646 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=38055,localport=49472], receiveBufferSize=530904
[junit4] 2> 1068646 INFO (qtp9965701-9842) [ ]
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> 1068646 INFO (qtp9965701-9842) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1068751 INFO (zkCallback-1446-thread-1) [ ]
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> 1069656 INFO (qtp9965701-9842) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1069664 INFO (qtp9965701-9842) [ ] o.a.s.s.IndexSchema
[control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 1069713 INFO (qtp9965701-9842) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1069721 INFO (qtp9965701-9842) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'control_collection_shard1_replica_n1' using configuration
from collection control_collection, trusted=true
[junit4] 2> 1069721 INFO (qtp9965701-9842) [ ]
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@9eda7a
[junit4] 2> 1069721 INFO (qtp9965701-9842) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1069721 INFO (qtp9965701-9842) [ ] o.a.s.c.SolrCore
[[control_collection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1069723 INFO (qtp9965701-9842) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1069725 WARN (qtp9965701-9842) [ ] 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> 1069752 INFO (qtp9965701-9842) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1069752 INFO (qtp9965701-9842) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1069752 INFO (qtp9965701-9842) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1069753 INFO (qtp9965701-9842) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1069753 INFO (qtp9965701-9842) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1069753 INFO (qtp9965701-9842) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@ee2bba[control_collection_shard1_replica_n1] main]
[junit4] 2> 1069754 INFO (qtp9965701-9842) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1069754 INFO (qtp9965701-9842) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1069755 INFO (qtp9965701-9842) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1069755 INFO (qtp9965701-9842) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1577192499340902400
[junit4] 2> 1069756 INFO (searcherExecutor-4061-thread-1) [ ]
o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher
Searcher@ee2bba[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1069758 INFO (qtp9965701-9842) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1069758 INFO (qtp9965701-9842) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1069758 INFO (qtp9965701-9842) [ ] o.a.s.c.SyncStrategy
Sync replicas to
http://127.0.0.1:42955/jhy/rs/control_collection_shard1_replica_n1/
[junit4] 2> 1069758 INFO (qtp9965701-9842) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1069758 INFO (qtp9965701-9842) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:42955/jhy/rs/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 1069759 INFO (qtp9965701-9842) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1069760 INFO (qtp9965701-9842) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42955/jhy/rs/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1069861 INFO (zkCallback-1446-thread-1) [ ]
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> 1069910 INFO (qtp9965701-9842) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1069911 INFO (qtp9965701-9842) [ ] 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=1265
[junit4] 2> 1069914 INFO (qtp9965701-9840) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1070013 INFO (zkCallback-1446-thread-1) [ ]
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> 1070543 INFO
(OverseerCollectionConfigSetProcessor-98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000)
[ ] 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> 1070914 INFO (qtp9965701-9840) [ ] 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:42955_jhy%252Frs&wt=javabin&version=2}
status=0 QTime=2375
[junit4] 2> 1070917 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1070918 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33527/solr ready
[junit4] 2> 1070918 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1070918 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=56880,localport=42955], receiveBufferSize:531000
[junit4] 2> 1070918 INFO (SocketProxy-Acceptor-42955) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=38055,localport=49482], receiveBufferSize=530904
[junit4] 2> 1070918 INFO (qtp9965701-9840) [ ]
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> 1070919 INFO (OverseerThreadFactory-4058-thread-2) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1070919 WARN (OverseerThreadFactory-4058-thread-2) [ ]
o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1)
without cores.
[junit4] 2> 1071122 INFO (qtp9965701-9840) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1071122 INFO (qtp9965701-9840) [ ] 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=203
[junit4] 2> 1071240 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001
of type NRT
[junit4] 2> 1071240 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1071241 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@559769{/jhy/rs,null,AVAILABLE}
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5f6a9c{HTTP/1.1,[http/1.1]}{127.0.0.1:33971}
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server Started @1072565ms
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhy/rs,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/tempDir-001/jetty1,
hostPort=41367,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores,
replicaType=NRT}
[junit4] 2> 1071242 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1071242 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T21:19:40.137535Z
[junit4] 2> 1071244 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1071244 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/solr.xml
[junit4] 2> 1071246 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1071248 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33527/solr
[junit4] 2> 1071253 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1071254 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1071254 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41367_jhy%2Frs
[junit4] 2> 1071255 INFO (zkCallback-1446-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071255 INFO (zkCallback-1458-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071255 INFO (zkCallback-1453-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1071345 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1071354 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1071354 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1071355 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores
[junit4] 2> 1071373 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52812,localport=41367], receiveBufferSize:531000
[junit4] 2> 1071373 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37914], receiveBufferSize=530904
[junit4] 2> 1071374 INFO (qtp5257045-9888) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:41367_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1071375 INFO
(OverseerCollectionConfigSetProcessor-98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000)
[ ] 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> 1071375 INFO (OverseerThreadFactory-4058-thread-3) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:41367_jhy%2Frs for creating new
replica
[junit4] 2> 1071376 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52816,localport=41367], receiveBufferSize:531000
[junit4] 2> 1071376 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37918], receiveBufferSize=530904
[junit4] 2> 1071376 INFO (qtp5257045-9890) [ ]
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> 1071376 INFO (qtp5257045-9890) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1072388 INFO (qtp5257045-9890) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1072402 INFO (qtp5257045-9890) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 1072458 INFO (qtp5257045-9890) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1072465 INFO (qtp5257045-9890) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n41' using configuration from
collection collection1, trusted=true
[junit4] 2> 1072465 INFO (qtp5257045-9890) [ ]
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@9eda7a
[junit4] 2> 1072465 INFO (qtp5257045-9890) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1072465 INFO (qtp5257045-9890) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n41] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores/collection1_shard2_replica_n41],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores/collection1_shard2_replica_n41/data/]
[junit4] 2> 1072467 INFO (qtp5257045-9890) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1072469 WARN (qtp5257045-9890) [ ] 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> 1072499 INFO (qtp5257045-9890) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1072500 INFO (qtp5257045-9890) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1072500 INFO (qtp5257045-9890) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1072500 INFO (qtp5257045-9890) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1072501 INFO (qtp5257045-9890) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1072501 INFO (qtp5257045-9890) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1d2e8d9[collection1_shard2_replica_n41] main]
[junit4] 2> 1072503 INFO (qtp5257045-9890) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1072503 INFO (qtp5257045-9890) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1072503 INFO (qtp5257045-9890) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1072504 INFO (searcherExecutor-4072-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n41] Registered new searcher
Searcher@1d2e8d9[collection1_shard2_replica_n41]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1072504 INFO (qtp5257045-9890) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1577192502223437824
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 1072509 INFO (qtp5257045-9890) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1072510 INFO (qtp5257045-9890) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/ shard2
[junit4] 2> 1072660 INFO (qtp5257045-9890) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1072662 INFO (qtp5257045-9890) [ ] 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=1285
[junit4] 2> 1072664 INFO (qtp5257045-9888) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:41367_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=1289
[junit4] 2> 1072756 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001
of type NRT
[junit4] 2> 1072757 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1072757 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@123c026{/jhy/rs,null,AVAILABLE}
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@150f5a8{HTTP/1.1,[http/1.1]}{127.0.0.1:33453}
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server Started @1074081ms
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhy/rs,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/tempDir-001/jetty2,
hostPort=43535,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001/cores}
[junit4] 2> 1072758 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1072758 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T21:19:41.653656Z
[junit4] 2> 1072760 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e35038669000a, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1072760 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1072760 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001/solr.xml
[junit4] 2> 1072762 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1072764 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33527/solr
[junit4] 2> 1072768 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1072769 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1072770 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43535_jhy%2Frs
[junit4] 2> 1072770 INFO (zkCallback-1446-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1072770 INFO (zkCallback-1464-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1072770 INFO (zkCallback-1453-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1072770 INFO (zkCallback-1458-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1072861 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1072869 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1072869 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1072871 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001/cores
[junit4] 2> 1072895 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=35878,localport=43535], receiveBufferSize:531000
[junit4] 2> 1072895 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33453,localport=41452], receiveBufferSize=530904
[junit4] 2> 1072896 INFO (qtp32573163-9922) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:43535_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1072897 INFO
(OverseerCollectionConfigSetProcessor-98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000)
[ ] 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> 1072897 INFO (OverseerThreadFactory-4058-thread-4) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:43535_jhy%2Frs for creating new
replica
[junit4] 2> 1072897 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=35882,localport=43535], receiveBufferSize:531000
[junit4] 2> 1072897 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33453,localport=41456], receiveBufferSize=530904
[junit4] 2> 1072897 INFO (qtp32573163-9924) [ ]
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> 1072898 INFO (qtp32573163-9924) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1073904 INFO (qtp32573163-9924) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1073911 INFO (qtp32573163-9924) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_n43] Schema name=test
[junit4] 2> 1073992 INFO (qtp32573163-9924) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1073998 INFO (qtp32573163-9924) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n43' using configuration from
collection collection1, trusted=true
[junit4] 2> 1073998 INFO (qtp32573163-9924) [ ]
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@9eda7a
[junit4] 2> 1073998 INFO (qtp32573163-9924) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1073998 INFO (qtp32573163-9924) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_n43] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001/cores/collection1_shard1_replica_n43],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-2-001/cores/collection1_shard1_replica_n43/data/]
[junit4] 2> 1074001 INFO (qtp32573163-9924) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1074002 WARN (qtp32573163-9924) [ ]
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> 1074031 INFO (qtp32573163-9924) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1074031 INFO (qtp32573163-9924) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1074032 INFO (qtp32573163-9924) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1074032 INFO (qtp32573163-9924) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1074033 INFO (qtp32573163-9924) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1074033 INFO (qtp32573163-9924) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@dbf674[collection1_shard1_replica_n43] main]
[junit4] 2> 1074034 INFO (qtp32573163-9924) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1074034 INFO (qtp32573163-9924) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1074034 INFO (qtp32573163-9924) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1074035 INFO (searcherExecutor-4083-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n43] Registered new searcher
Searcher@dbf674[collection1_shard1_replica_n43]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1074035 INFO (qtp32573163-9924) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1577192503828807680
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/ has no replicas
[junit4] 2> 1074038 INFO (qtp32573163-9924) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1074039 INFO (qtp32573163-9924) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/ shard1
[junit4] 2> 1074190 INFO (qtp32573163-9924) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1074190 INFO (qtp32573163-9924) [ ] 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=1292
[junit4] 2> 1074191 INFO (qtp32573163-9922) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43535_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=1295
[junit4] 2> 1074271 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001
of type NRT
[junit4] 2> 1074271 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1074272 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@11d0295{/jhy/rs,null,AVAILABLE}
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@b7e1e2{HTTP/1.1,[http/1.1]}{127.0.0.1:39221}
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.e.j.s.Server Started @1075596ms
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jhy/rs,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/tempDir-001/jetty3,
hostPort=40467,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001/cores}
[junit4] 2> 1074273 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.1.0
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1074273 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-30T21:19:43.168469Z
[junit4] 2> 1074275 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1074275 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001/solr.xml
[junit4] 2> 1074277 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1074279 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33527/solr
[junit4] 2> 1074283 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1074283 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1074284 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40467_jhy%2Frs
[junit4] 2> 1074284 INFO (zkCallback-1446-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1074284 INFO (zkCallback-1453-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1074284 INFO (zkCallback-1458-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1074284 INFO (zkCallback-1464-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1074285 INFO (zkCallback-1470-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1074388 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1074397 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1074397 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1074398 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001/cores
[junit4] 2> 1074432 INFO (qtp32573163-9923) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:40467_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1074433 INFO
(OverseerCollectionConfigSetProcessor-98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000)
[ ] 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> 1074433 INFO (OverseerThreadFactory-4058-thread-5) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:40467_jhy%2Frs for creating new
replica
[junit4] 2> 1074434 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=41332,localport=40467], receiveBufferSize:531000
[junit4] 2> 1074434 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39221,localport=51724], receiveBufferSize=530904
[junit4] 2> 1074435 INFO (qtp7104045-9956) [ ]
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> 1074435 INFO (qtp7104045-9956) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1075456 INFO (qtp7104045-9956) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1075478 INFO (qtp7104045-9956) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n45] Schema name=test
[junit4] 2> 1075527 INFO (qtp7104045-9956) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1075533 INFO (qtp7104045-9956) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n45' using configuration from
collection collection1, trusted=true
[junit4] 2> 1075533 INFO (qtp7104045-9956) [ ]
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@9eda7a
[junit4] 2> 1075533 INFO (qtp7104045-9956) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1075533 INFO (qtp7104045-9956) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n45] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001/cores/collection1_shard2_replica_n45],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-3-001/cores/collection1_shard2_replica_n45/data/]
[junit4] 2> 1075535 INFO (qtp7104045-9956) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1075537 WARN (qtp7104045-9956) [ ] 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> 1075567 INFO (qtp7104045-9956) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1075567 INFO (qtp7104045-9956) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1075568 INFO (qtp7104045-9956) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1075568 INFO (qtp7104045-9956) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1075569 INFO (qtp7104045-9956) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1075569 INFO (qtp7104045-9956) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@12ec8ed[collection1_shard2_replica_n45] main]
[junit4] 2> 1075569 INFO (qtp7104045-9956) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1075570 INFO (qtp7104045-9956) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1075570 INFO (qtp7104045-9956) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1075571 INFO (searcherExecutor-4094-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n45] Registered new searcher
Searcher@12ec8ed[collection1_shard2_replica_n45]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1075571 INFO (qtp7104045-9956) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1577192505439420416
[junit4] 2> 1075573 INFO (qtp7104045-9956) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard2_replica_n45
[junit4] 2> 1075573 INFO (updateExecutor-1467-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1075573 INFO (qtp7104045-9956) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n45&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1138
[junit4] 2> 1075573 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1075574 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1075574 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard2_replica_n45]
[junit4] 2> 1075574 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1075574 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard2_replica_n45] as recovering, leader is
[http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/] and I am
[http://127.0.0.1:40467/jhy/rs/collection1_shard2_replica_n45/]
[junit4] 2> 1075575 INFO (qtp32573163-9923) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:40467_jhy%252Frs&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=1142
[junit4] 2> 1075578 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:41367/jhy/rs]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard2_replica_n41&nodeName=127.0.0.1:40467_jhy%252Frs&coreNodeName=core_node46&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1075578 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52864,localport=41367], receiveBufferSize:531000
[junit4] 2> 1075578 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37966], receiveBufferSize=530904
[junit4] 2> 1075579 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1075579 INFO (qtp5257045-9884) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node46, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 1075579 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait
30000 for each attempt
[junit4] 2> 1075579 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30000
[junit4] 2> 1075579 INFO (qtp5257045-9884) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_n41,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:40467_jhy%2Frs,
coreNodeName=core_node46, onlyIfActiveCheckResult=false, nodeProps:
core_node46:{"core":"collection1_shard2_replica_n45","base_url":"http://127.0.0.1:40467/jhy/rs","node_name":"127.0.0.1:40467_jhy%2Frs","state":"down","type":"NRT"}
[junit4] 2> 1076435 INFO
(OverseerCollectionConfigSetProcessor-98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1076579 INFO (qtp5257045-9884) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_n41,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:40467_jhy%2Frs,
coreNodeName=core_node46, onlyIfActiveCheckResult=false, nodeProps:
core_node46:{"core":"collection1_shard2_replica_n45","base_url":"http://127.0.0.1:40467/jhy/rs","node_name":"127.0.0.1:40467_jhy%2Frs","state":"recovering","type":"NRT"}
[junit4] 2> 1076579 INFO (qtp5257045-9884) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node46, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1076579 INFO (qtp5257045-9884) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:40467_jhy%252Frs&onlyIfLeaderActive=true&core=collection1_shard2_replica_n41&coreNodeName=core_node46&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 1077080 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/] -
recoveringAfterStartup=[true]
[junit4] 2> 1077080 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n45
url=http://127.0.0.1:40467/jhy/rs START
replicas=[http://127.0.0.1:41367/jhy/rs/collection1_shard2_replica_n41/]
nUpdates=100
[junit4] 2> 1077080 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52874,localport=41367], receiveBufferSize:531000
[junit4] 2> 1077081 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37976], receiveBufferSize=530904
[junit4] 2> 1077082 INFO (qtp5257045-9884) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1077082 INFO (qtp5257045-9884) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n41] webapp=/jhy/rs path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1077083 INFO (recoveryExecutor-1468-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1077579 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1077580 INFO (qtp9965701-9842) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1577192507546009600,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1077580 INFO (qtp9965701-9842) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1077580 INFO (qtp9965701-9842) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1077580 INFO (qtp9965701-9842) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [control_collection_shard1_replica_n1]
webapp=/jhy/rs path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 0
[junit4] 2> 1077581 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=35924,localport=43535], receiveBufferSize:531000
[junit4] 2> 1077581 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52882,localport=41367], receiveBufferSize:531000
[junit4] 2> 1077581 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=41360,localport=40467], receiveBufferSize:531000
[junit4] 2> 1077581 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33453,localport=41500], receiveBufferSize=530904
[junit4] 2> 1077581 INFO (qtp32573163-9919) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1577192507547058176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1077581 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37988], receiveBufferSize=530904
[junit4] 2> 1077582 INFO (qtp5257045-9885) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1577192507547058176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1077582 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39221,localport=51754], receiveBufferSize=530904
[junit4] 2> 1077582 INFO (qtp32573163-9919) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1077582 INFO (qtp5257045-9885) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1077583 INFO (qtp7104045-9958) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1577192507548106752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1077583 INFO (qtp32573163-9919) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1077583 INFO (qtp5257045-9885) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1077583 INFO (qtp32573163-9919) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n43]
webapp=/jhy/rs path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 1077583 INFO (qtp5257045-9885) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n41]
webapp=/jhy/rs path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 1077583 INFO (qtp7104045-9958) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1077583 INFO (qtp7104045-9958) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1077583 INFO (qtp7104045-9958) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n45]
webapp=/jhy/rs path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43535/jhy/rs/collection1_shard1_replica_n43/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1077583 INFO (qtp32573163-9918) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n43]
webapp=/jhy/rs path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 2
[junit4] 2> 1077584 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=52892,localport=41367], receiveBufferSize:531000
[junit4] 2> 1077584 INFO (SocketProxy-Acceptor-41367) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33971,localport=37994], receiveBufferSize=530904
[junit4] 2> 1077584 INFO (qtp5257045-9884) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n41] webapp=/jhy/rs path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1077585 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=41370,localport=40467], receiveBufferSize:531000
[junit4] 2> 1077585 INFO (SocketProxy-Acceptor-40467) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39221,localport=51762], receiveBufferSize=530904
[junit4] 2> 1077585 INFO (qtp7104045-9956) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n45] webapp=/jhy/rs path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1077586 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=35944,localport=43535], receiveBufferSize:531000
[junit4] 2> 1077586 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33453,localport=41518], receiveBufferSize=530904
[junit4] 2> 1077586 INFO (qtp32573163-9923) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_n43] webapp=/jhy/rs path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1079586 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection with stateFormat=1:
c8n_1x3_lf
[junit4] 2> 1079588 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=35950,localport=43535], receiveBufferSize:531000
[junit4] 2> 1079588 INFO (SocketProxy-Acceptor-43535) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=33453,localport=41524], receiveBufferSize=530904
[junit4] 2> 1079588 INFO (qtp32573163-9918) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=0&maxShardsPerNode=1&collection.configName=conf1&nrtReplicas=3&name=c8n_1x3_lf&action=CREATE&numShards=1&tlogReplicas=0&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1079589 INFO (OverseerThreadFactory-4058-thread-5) [ ]
o.a.s.c.CreateCollectionCmd Create collection c8n_1x3_lf
[junit4] 2> 1079692 INFO (qtp5257045-9883) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=c8n_1x3_lf_shard1_replica_n1&action=CREATE&numShards=1&collection=c8n_1x3_lf&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1079693 INFO (qtp32573163-9920) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=c8n_1x3_lf_shard1_replica_n2&action=CREATE&numShards=1&collection=c8n_1x3_lf&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1079693 INFO (qtp9965701-9835) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=c8n_1x3_lf_shard1_replica_n3&action=CREATE&numShards=1&collection=c8n_1x3_lf&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1081028 INFO (qtp5257045-9883) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1081028 INFO (qtp9965701-9835) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1081029 INFO (qtp32573163-9920) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1081043 INFO (qtp9965701-9835) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n3] Schema name=test
[junit4] 2> 1081043 INFO (qtp5257045-9883) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n1] Schema name=test
[junit4] 2> 1081052 INFO (qtp32573163-9920) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n2] Schema name=test
[junit4] 2> 1081134 INFO (qtp5257045-9883) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1081138 INFO (qtp9965701-9835) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1081140 INFO (qtp5257045-9883) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'c8n_1x3_lf_shard1_replica_n1' using configuration from
collection c8n_1x3_lf, trusted=true
[junit4] 2> 1081141 INFO (qtp5257045-9883) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.c8n_1x3_lf.shard1.replica_n1' (registry
'solr.core.c8n_1x3_lf.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1081141 INFO (qtp5257045-9883) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1081141 INFO (qtp5257045-9883) [ ] o.a.s.c.SolrCore
[[c8n_1x3_lf_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores/c8n_1x3_lf_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/shard-1-001/cores/c8n_1x3_lf_shard1_replica_n1/data/]
[junit4] 2> 1081143 INFO (qtp5257045-9883) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1081145 WARN (qtp5257045-9883) [ ] 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> 1081146 INFO (qtp9965701-9835) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'c8n_1x3_lf_shard1_replica_n3' using configuration from
collection c8n_1x3_lf, trusted=true
[junit4] 2> 1081147 INFO (qtp9965701-9835) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.c8n_1x3_lf.shard1.replica_n3' (registry
'solr.core.c8n_1x3_lf.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@9eda7a
[junit4] 2> 1081147 INFO (qtp9965701-9835) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1081147 INFO (qtp9965701-9835) [ ] o.a.s.c.SolrCore
[[c8n_1x3_lf_shard1_replica_n3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores/c8n_1x3_lf_shard1_replica_n3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001/control-001/cores/c8n_1x3_lf_shard1_replica_n3/data/]
[junit4] 2> 1081150 INFO (qtp9965701-9835) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.46746640526062844]
[junit4] 2> 1081151 WARN (qtp9965701-9835) [ ] 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> 1081156 INFO (qtp32573163-9920) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1081162 INFO (qtp32573163-9920) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'c8n_1x3_lf_shard1_replica_n2' using configuration from
collection c8n_1x3_lf, trusted=true
[junit4] 2> 1081162 INFO (qtp32573163-9920) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.c8n_1x3_lf.shard1.replica_n2' (registry
'solr.core.c8n_1x3_lf.shard1.replica_n2') enabled
[...truncated too long message...]
andler Stopped o.e.j.s.ServletContextHandler@11d0295{/jhy/rs,null,UNAVAILABLE}
[junit4] 2> 1198096 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
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> 1198096 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33527 33527
[junit4] 2> 1198130 INFO (Thread-3028) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:33527 33527
[junit4] 2> 1198130 WARN (Thread-3028) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/c8n_1x3_lf/state.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/98574531104931844-127.0.0.1:42955_jhy%2Frs-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1198130 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SocketProxy Closing 0 connections to: http://127.0.0.1:41367/jhy/rs,
target: http://127.0.0.1:33971/jhy/rs
[junit4] 2> 1198130 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SocketProxy Closing 3 connections to: http://127.0.0.1:40467/jhy/rs,
target: http://127.0.0.1:39221/jhy/rs
[junit4] 2> 1198131 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SocketProxy Closing 1 connections to: http://127.0.0.1:42955/jhy/rs,
target: http://127.0.0.1:38055/jhy/rs
[junit4] 2> 1198131 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[321FEF2686CFF28B]) [ ]
o.a.s.c.SocketProxy Closing 6 connections to: http://127.0.0.1:43535/jhy/rs,
target: http://127.0.0.1:33453/jhy/rs
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=LeaderFailoverAfterPartitionTest -Dtests.method=test
-Dtests.seed=321FEF2686CFF28B -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=pt-BR -Dtests.timezone=Asia/Aqtau -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 130s J1 | LeaderFailoverAfterPartitionTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected 2 of 3
replicas to be active but only found 1;
[core_node4:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43535/jhy/rs","node_name":"127.0.0.1:43535_jhy%2Frs","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//clusterstate.json/29)={
[junit4] > "pullReplicas":"0",
[junit4] > "replicationFactor":"1",
[junit4] > "shards":{"shard1":{
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active",
[junit4] > "replicas":{
[junit4] > "core_node4":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n2",
[junit4] > "base_url":"http://127.0.0.1:43535/jhy/rs";,
[junit4] > "node_name":"127.0.0.1:43535_jhy%2Frs",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "leader":"true"},
[junit4] > "core_node5":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n1",
[junit4] > "base_url":"http://127.0.0.1:41367/jhy/rs";,
[junit4] > "node_name":"127.0.0.1:41367_jhy%2Frs",
[junit4] > "state":"down",
[junit4] > "type":"NRT"},
[junit4] > "core_node6":{
[junit4] > "state":"down",
[junit4] > "base_url":"http://127.0.0.1:42955/jhy/rs";,
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n3",
[junit4] > "node_name":"127.0.0.1:42955_jhy%2Frs",
[junit4] > "type":"NRT"}}}},
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"3",
[junit4] > "tlogReplicas":"0"}
[junit4] > at
__randomizedtesting.SeedInfo.seed([321FEF2686CFF28B:BA4BD0FC28339F73]:0)
[junit4] > at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.testRf3WithLeaderFailover(LeaderFailoverAfterPartitionTest.java:169)
[junit4] > at
org.apache.solr.cloud.LeaderFailoverAfterPartitionTest.test(LeaderFailoverAfterPartitionTest.java:56)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderFailoverAfterPartitionTest_321FEF2686CFF28B-001
[junit4] 2> Aug 30, 2017 9:21:47 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=RandomSimilarity(queryNorm=false): {}, locale=pt-BR, timezone=Asia/Aqtau
[junit4] 2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 9
(32-bit)/cpus=8,threads=1,free=333491216,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestObjectReleaseTracker,
TestShardHandlerFactory, TestComplexPhraseQParserPlugin, TestCoreContainer,
TestStressVersions, SolrCmdDistributorTest, RuleEngineTest, FileUtilsTest,
TemplateUpdateProcessorTest, DistributedQueueTest, DistribCursorPagingTest,
TestBM25SimilarityFactory, UUIDUpdateProcessorFallbackTest,
DistributedTermsComponentTest, TestManagedSchema, DocValuesTest,
TestMacroExpander, AddSchemaFieldsUpdateProcessorFactoryTest, HLLUtilTest,
TestAddFieldRealTimeGet, SoftAutoCommitTest, DirectSolrSpellCheckerTest,
ChaosMonkeySafeLeaderTest, TestDynamicFieldResource, TestRTGBase,
DistributedFacetExistsSmallTest, ReplaceNodeTest, TestPointFields,
TestZkAclsWithHadoopAuth, TriLevelCompositeIdRoutingTest,
AnalysisAfterCoreReloadTest, TestSolr4Spatial2, SpellCheckComponentTest,
DisMaxRequestHandlerTest, MoveReplicaHDFSTest, TestConfigSets,
UpdateRequestProcessorFactoryTest, BasicAuthStandaloneTest,
OpenCloseCoreStressTest, AssignBackwardCompatibilityTest,
CdcrReplicationHandlerTest, TestCloudInspectUtil,
OverriddenZkACLAndCredentialsProvidersTest, ScriptEngineTest,
TestJavabinTupleStreamParser, TestPullReplicaErrorHandling,
AutoScalingHandlerTest, TestOnReconnectListenerSupport,
TestHttpShardHandlerFactory, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
MoreLikeThisHandlerTest, TestExactStatsCache, TestRequestStatusCollectionAPI,
TestPerFieldSimilarity, HdfsRecoveryZkTest, DeleteInactiveReplicaTest,
TestSearcherReuse, CoreSorterTest, TestCursorMarkWithoutUniqueKey,
TestFieldCacheSort, TestEmbeddedSolrServerConstructors,
PeerSyncReplicationTest, CacheHeaderTest,
PeerSyncWithIndexFingerprintCachingTest, TestXIncludeConfig,
TestSlowCompositeReaderWrapper, TestTestInjection, TestCoreDiscovery,
OverseerRolesTest, TestNonDefinedSimilarityFactory, HdfsDirectoryFactoryTest,
ShufflingReplicaListTransformerTest, TestLocalFSCloudBackupRestore,
TestBlendedInfixSuggestions, TestStressInPlaceUpdates, SolrXmlInZkTest,
DistributedQueryComponentCustomSortTest, TestInitParams, RollingRestartTest,
MultiThreadedOCPTest, TestSubQueryTransformer, JsonLoaderTest,
BooleanFieldTest, RequestLoggingTest, TestCryptoKeys, JavabinLoaderTest,
TestConfigReload, TestPKIAuthenticationPlugin, TestCloudPseudoReturnFields,
SolrMetricsIntegrationTest, SampleTest, CSVRequestHandlerTest, SliceStateTest,
AlternateDirectoryTest, TestFastLRUCache,
StatelessScriptUpdateProcessorFactoryTest, TestNumericRangeQuery32,
TestSolrCloudWithKerberosAlt, SpatialFilterTest, DistributedMLTComponentTest,
TestManagedResource, SchemaVersionSpecificBehaviorTest, TestExportWriter,
ClusterStateUpdateTest, LeaderFailoverAfterPartitionTest]
[junit4] Completed [353/731 (1!)] on J1 in 130.24s, 1 test, 1 failure <<<
FAILURES!
[...truncated 40312 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/build.xml:810: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/build.xml:690: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/build.xml:678: Source checkout is
modified!!! Offending files:
* solr/licenses/httpmime-4.5.3.jar.sha1
Total time: 74 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
