Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/104/
Java: 32bit/jdk-9-ea+178 -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_node3:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43871/_/jt","node_name":"127.0.0.1:43871__%2Fjt","state":"active","type":"NRT","leader":"true"}];
clusterState:
DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
"pullReplicas":"0", "replicationFactor":"1", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node1":{ "state":"down",
"base_url":"http://127.0.0.1:45497/_/jt";,
"core":"c8n_1x3_lf_shard1_replica_n1",
"node_name":"127.0.0.1:45497__%2Fjt", "type":"NRT"},
"core_node2":{ "core":"c8n_1x3_lf_shard1_replica_n3",
"base_url":"http://127.0.0.1:35917/_/jt";,
"node_name":"127.0.0.1:35917__%2Fjt", "state":"down",
"type":"NRT"}, "core_node3":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43871/_/jt";,
"node_name":"127.0.0.1:43871__%2Fjt", "state":"active",
"type":"NRT", "leader":"true"}}}}, "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_node3:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43871/_/jt","node_name":"127.0.0.1:43871__%2Fjt","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
"pullReplicas":"0",
"replicationFactor":"1",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node1":{
"state":"down",
"base_url":"http://127.0.0.1:45497/_/jt";,
"core":"c8n_1x3_lf_shard1_replica_n1",
"node_name":"127.0.0.1:45497__%2Fjt",
"type":"NRT"},
"core_node2":{
"core":"c8n_1x3_lf_shard1_replica_n3",
"base_url":"http://127.0.0.1:35917/_/jt";,
"node_name":"127.0.0.1:35917__%2Fjt",
"state":"down",
"type":"NRT"},
"core_node3":{
"core":"c8n_1x3_lf_shard1_replica_n2",
"base_url":"http://127.0.0.1:43871/_/jt";,
"node_name":"127.0.0.1:43871__%2Fjt",
"state":"active",
"type":"NRT",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([5D663409B0D3410B:D5320BD31E2F2CF3]: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:985)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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 11190 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderFailoverAfterPartitionTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/init-core-data-001
[junit4] 2> 405658 WARN
(SUITE-LeaderFailoverAfterPartitionTest-seed#[5D663409B0D3410B]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=65 numCloses=65
[junit4] 2> 405658 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[5D663409B0D3410B]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 405658 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[5D663409B0D3410B]-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> 405658 INFO
(SUITE-LeaderFailoverAfterPartitionTest-seed#[5D663409B0D3410B]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/jt
[junit4] 2> 405660 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 405660 INFO (Thread-468) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 405660 INFO (Thread-468) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 405666 ERROR (Thread-468) [ ] 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> 405760 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkTestServer start zk server on port:34251
[junit4] 2> 405768 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405769 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405769 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405770 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405770 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405771 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405771 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405772 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405772 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405773 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405773 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 405774 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 405883 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 405884 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@17ab642{/_/jt,null,AVAILABLE}
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@11a91bc{HTTP/1.1,[http/1.1]}{127.0.0.1:36381}
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server Started @407009ms
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/jt,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/tempDir-001/control/data,
hostPort=42269,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/control-001/cores}
[junit4] 2> 405888 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.1.0
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 405888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-19T09:06:37.154927Z
[junit4] 2> 405900 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 405900 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/control-001/solr.xml
[junit4] 2> 405903 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 405905 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34251/solr
[junit4] 2> 405950 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 405950 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:42269__%2Fjt
[junit4] 2> 405951 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer
(id=98333831785807876-127.0.0.1:42269__%2Fjt-n_0000000000) starting
[junit4] 2> 405953 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42269__%2Fjt
[junit4] 2> 405954 INFO (zkCallback-308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 406025 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 406029 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 406029 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 406031 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/control-001/cores
[junit4] 2> 406102 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 406102 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34251/solr ready
[junit4] 2> 406103 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=48180,localport=42269], receiveBufferSize:531000
[junit4] 2> 406103 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36381,localport=48872], receiveBufferSize=530904
[junit4] 2> 406105 INFO (qtp8790374-2042) [ ]
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:42269__%252Fjt&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 406111 INFO (OverseerThreadFactory-641-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 406213 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=48184,localport=42269], receiveBufferSize:531000
[junit4] 2> 406213 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36381,localport=48876], receiveBufferSize=530904
[junit4] 2> 406214 INFO (qtp8790374-2044) [ ]
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> 406214 INFO (qtp8790374-2044) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 406316 INFO (zkCallback-308-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> 407223 INFO (qtp8790374-2044) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.1.0
[junit4] 2> 407231 INFO (qtp8790374-2044) [ ] o.a.s.s.IndexSchema
[control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 407303 INFO (qtp8790374-2044) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 407312 INFO (qtp8790374-2044) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'control_collection_shard1_replica_n1' using configuration
from collection control_collection, trusted=true
[junit4] 2> 407312 INFO (qtp8790374-2044) [ ]
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@7fcf1a
[junit4] 2> 407312 INFO (qtp8790374-2044) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 407312 INFO (qtp8790374-2044) [ ] 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/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 407315 INFO (qtp8790374-2044) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=21.1943359375,
floorSegmentMB=0.580078125, forceMergeDeletesPctAllowed=1.2157814188153537,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.706646999988747
[junit4] 2> 407317 WARN (qtp8790374-2044) [ ] 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> 407356 INFO (qtp8790374-2044) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 407356 INFO (qtp8790374-2044) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 407357 INFO (qtp8790374-2044) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 407357 INFO (qtp8790374-2044) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 407358 INFO (qtp8790374-2044) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@1161d21
[junit4] 2> 407359 INFO (qtp8790374-2044) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@188c0ab[control_collection_shard1_replica_n1] main]
[junit4] 2> 407359 INFO (qtp8790374-2044) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 407360 INFO (qtp8790374-2044) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 407360 INFO (qtp8790374-2044) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 407361 INFO (searcherExecutor-644-thread-1) [ ]
o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher
Searcher@188c0ab[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 407361 INFO (qtp8790374-2044) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1573341310355505152
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ] o.a.s.c.SyncStrategy
Sync replicas to
http://127.0.0.1:42269/_/jt/control_collection_shard1_replica_n1/
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:42269/_/jt/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 407365 INFO (qtp8790374-2044) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 407367 INFO (qtp8790374-2044) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42269/_/jt/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 407468 INFO (zkCallback-308-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> 407518 INFO (qtp8790374-2044) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 407519 INFO (qtp8790374-2044) [ ] 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=1305
[junit4] 2> 407521 INFO (qtp8790374-2042) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 407620 INFO (zkCallback-308-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> 408113 INFO
(OverseerCollectionConfigSetProcessor-98333831785807876-127.0.0.1:42269__%2Fjt-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> 408521 INFO (qtp8790374-2042) [ ] 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:42269__%252Fjt&wt=javabin&version=2}
status=0 QTime=2416
[junit4] 2> 408526 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 408527 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34251/solr ready
[junit4] 2> 408527 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 408527 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=48206,localport=42269], receiveBufferSize:531000
[junit4] 2> 408531 INFO (SocketProxy-Acceptor-42269) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36381,localport=48898], receiveBufferSize=530904
[junit4] 2> 408531 INFO (qtp8790374-2042) [ ]
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=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 408532 INFO (OverseerThreadFactory-641-thread-2) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 408532 WARN (OverseerThreadFactory-641-thread-2) [ ]
o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1)
without cores.
[junit4] 2> 408734 INFO (qtp8790374-2042) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 408734 INFO (qtp8790374-2042) [ ] 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=2&wt=javabin&version=2}
status=0 QTime=203
[junit4] 2> 408821 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001
of type NRT
[junit4] 2> 408822 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 408822 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@25a6e{/_/jt,null,AVAILABLE}
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@16ef243{HTTP/1.1,[http/1.1]}{127.0.0.1:34421}
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server Started @409943ms
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/jt,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/tempDir-001/jetty1,
hostPort=43871,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001/cores}
[junit4] 2> 408823 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.1.0
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 408823 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-19T09:06:40.089538Z
[junit4] 2> 408826 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 408826 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001/solr.xml
[junit4] 2> 408828 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 408830 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34251/solr
[junit4] 2> 408837 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 408837 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 408838 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43871__%2Fjt
[junit4] 2> 408839 INFO (zkCallback-308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 408839 INFO (zkCallback-315-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 408839 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 408888 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 408892 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 408892 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 408893 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001/cores
[junit4] 2> 408905 INFO (qtp8790374-2043) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:43871__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 408906 INFO
(OverseerCollectionConfigSetProcessor-98333831785807876-127.0.0.1:42269__%2Fjt-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> 408906 INFO (OverseerThreadFactory-641-thread-3) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:43871__%2Fjt for creating new
replica
[junit4] 2> 408907 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51366,localport=43871], receiveBufferSize:531000
[junit4] 2> 408908 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34510], receiveBufferSize=530904
[junit4] 2> 408909 INFO (qtp23563170-2090) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 408909 INFO (qtp23563170-2090) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 409011 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 409915 INFO (qtp23563170-2090) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 409922 INFO (qtp23563170-2090) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n0] Schema name=test
[junit4] 2> 410068 INFO (qtp23563170-2090) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 410081 INFO (qtp23563170-2090) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n0' using configuration from
collection collection1, trusted=true
[junit4] 2> 410082 INFO (qtp23563170-2090) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n0' (registry
'solr.core.collection1.shard2.replica_n0') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 410082 INFO (qtp23563170-2090) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 410082 INFO (qtp23563170-2090) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n0] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001/cores/collection1_shard2_replica_n0],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-1-001/cores/collection1_shard2_replica_n0/data/]
[junit4] 2> 410088 INFO (qtp23563170-2090) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=21.1943359375,
floorSegmentMB=0.580078125, forceMergeDeletesPctAllowed=1.2157814188153537,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.706646999988747
[junit4] 2> 410090 WARN (qtp23563170-2090) [ ] 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> 410130 INFO (qtp23563170-2090) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 410130 INFO (qtp23563170-2090) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 410131 INFO (qtp23563170-2090) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 410131 INFO (qtp23563170-2090) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 410132 INFO (qtp23563170-2090) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@555320
[junit4] 2> 410133 INFO (qtp23563170-2090) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@107e5ca[collection1_shard2_replica_n0] main]
[junit4] 2> 410134 INFO (qtp23563170-2090) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 410134 INFO (qtp23563170-2090) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 410134 INFO (qtp23563170-2090) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 410135 INFO (searcherExecutor-655-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n0] Registered new searcher
Searcher@107e5ca[collection1_shard2_replica_n0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 410135 INFO (qtp23563170-2090) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1573341313264254976
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/ has no replicas
[junit4] 2> 410139 INFO (qtp23563170-2090) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 410141 INFO (qtp23563170-2090) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/ shard2
[junit4] 2> 410242 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 410292 INFO (qtp23563170-2090) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 410294 INFO (qtp23563170-2090) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1385
[junit4] 2> 410296 INFO (qtp8790374-2043) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:43871__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=1390
[junit4] 2> 410398 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 410462 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001
of type NRT
[junit4] 2> 410463 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@bf012a{/_/jt,null,AVAILABLE}
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1b6920{HTTP/1.1,[http/1.1]}{127.0.0.1:36123}
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server Started @411585ms
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/jt,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/tempDir-001/jetty2,
hostPort=35917,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001/cores}
[junit4] 2> 410464 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.1.0
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 410464 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 410465 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-19T09:06:41.731016Z
[junit4] 2> 410467 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 410467 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001/solr.xml
[junit4] 2> 410469 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 410471 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34251/solr
[junit4] 2> 410480 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 410480 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 410481 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35917__%2Fjt
[junit4] 2> 410481 INFO (zkCallback-308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 410482 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 410481 INFO (zkCallback-315-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 410486 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 410544 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 410547 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 410547 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 410548 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001/cores
[junit4] 2> 410565 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=47262,localport=35917], receiveBufferSize:531000
[junit4] 2> 410566 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36123,localport=45544], receiveBufferSize=530904
[junit4] 2> 410566 INFO (qtp218205-2123) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:35917__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 410567 INFO
(OverseerCollectionConfigSetProcessor-98333831785807876-127.0.0.1:42269__%2Fjt-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> 410567 INFO (OverseerThreadFactory-641-thread-4) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:35917__%2Fjt for creating new
replica
[junit4] 2> 410568 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=47266,localport=35917], receiveBufferSize:531000
[junit4] 2> 410568 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36123,localport=45548], receiveBufferSize=530904
[junit4] 2> 410568 INFO (qtp218205-2119) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 410568 INFO (qtp218205-2119) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 410670 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 410670 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 411578 INFO (qtp218205-2119) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.1.0
[junit4] 2> 411589 INFO (qtp218205-2119) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_n0] Schema name=test
[junit4] 2> 411779 INFO (qtp218205-2119) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 411794 INFO (qtp218205-2119) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n0' using configuration from
collection collection1, trusted=true
[junit4] 2> 411795 INFO (qtp218205-2119) [ ] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard1.replica_n0' (registry
'solr.core.collection1.shard1.replica_n0') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 411795 INFO (qtp218205-2119) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 411795 INFO (qtp218205-2119) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_n0] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001/cores/collection1_shard1_replica_n0],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-2-001/cores/collection1_shard1_replica_n0/data/]
[junit4] 2> 411797 INFO (qtp218205-2119) [ ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=13, maxMergeAtOnceExplicit=33,
maxMergedSegmentMB=21.1943359375, floorSegmentMB=0.580078125,
forceMergeDeletesPctAllowed=1.2157814188153537, segmentsPerTier=19.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.706646999988747
[junit4] 2> 411799 WARN (qtp218205-2119) [ ] 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> 416846 INFO (qtp218205-2119) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 416846 INFO (qtp218205-2119) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 416847 INFO (qtp218205-2119) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 416847 INFO (qtp218205-2119) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 416848 INFO (qtp218205-2119) [ ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@6b9529
[junit4] 2> 416848 INFO (qtp218205-2119) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@174b374[collection1_shard1_replica_n0] main]
[junit4] 2> 416849 INFO (qtp218205-2119) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 416849 INFO (qtp218205-2119) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 416850 INFO (qtp218205-2119) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 416851 INFO (searcherExecutor-666-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n0] Registered new searcher
Searcher@174b374[collection1_shard1_replica_n0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 416851 INFO (qtp218205-2119) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1573341320306491392
[junit4] 2> 416854 INFO (qtp218205-2119) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 416854 INFO (qtp218205-2119) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 416854 INFO (qtp218205-2119) [ ] o.a.s.c.SyncStrategy Sync
replicas to http://127.0.0.1:35917/_/jt/collection1_shard1_replica_n0/
[junit4] 2> 416854 INFO (qtp218205-2119) [ ] o.a.s.c.SyncStrategy Sync
Success - now sync replicas to me
[junit4] 2> 416854 INFO (qtp218205-2119) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:35917/_/jt/collection1_shard1_replica_n0/ has no replicas
[junit4] 2> 416854 INFO (qtp218205-2119) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 416855 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 416855 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 416860 INFO (qtp218205-2119) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35917/_/jt/collection1_shard1_replica_n0/ shard1
[junit4] 2> 416962 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 416962 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 417011 INFO (qtp218205-2119) [ ] o.a.s.c.ZkController I am
the leader, no recovery necessary
[junit4] 2> 417012 INFO (qtp218205-2119) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=6444
[junit4] 2> 417014 INFO (qtp218205-2123) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:35917__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=6447
[junit4] 2> 417101 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001
of type NRT
[junit4] 2> 417102 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 417102 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@258999{/_/jt,null,AVAILABLE}
[junit4] 2> 417103 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1bf6797{HTTP/1.1,[http/1.1]}{127.0.0.1:39155}
[junit4] 2> 417103 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.Server Started @418224ms
[junit4] 2> 417103 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/jt,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/tempDir-001/jetty3,
hostPort=45497,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001/cores}
[junit4] 2> 417103 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 417104 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.1.0
[junit4] 2> 417104 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 417104 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 417104 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-19T09:06:48.370306Z
[junit4] 2> 417106 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 417106 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001/solr.xml
[junit4] 2> 417118 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 417119 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 417120 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 417121 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34251/solr
[junit4] 2> 417136 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 417137 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 417138 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45497__%2Fjt
[junit4] 2> 417138 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 417139 INFO (zkCallback-315-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 417139 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 417139 INFO (zkCallback-308-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 417140 INFO (zkCallback-332-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 417203 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 417207 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 417207 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 417208 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001/cores
[junit4] 2> 417242 INFO (qtp218205-2124) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:45497__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 417243 INFO
(OverseerCollectionConfigSetProcessor-98333831785807876-127.0.0.1:42269__%2Fjt-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> 417244 INFO (OverseerThreadFactory-641-thread-5) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:45497__%2Fjt for creating new
replica
[junit4] 2> 417244 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=39616,localport=45497], receiveBufferSize:531000
[junit4] 2> 417251 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39155,localport=52100], receiveBufferSize=530904
[junit4] 2> 417252 INFO (qtp22191139-2157) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 417252 INFO (qtp22191139-2157) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 417354 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 417354 INFO (zkCallback-332-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 417354 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 418262 INFO (qtp22191139-2157) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 418273 INFO (qtp22191139-2157) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n1] Schema name=test
[junit4] 2> 418331 INFO (qtp22191139-2157) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 418341 INFO (qtp22191139-2157) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n1' using configuration from
collection collection1, trusted=true
[junit4] 2> 418342 INFO (qtp22191139-2157) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_n1' (registry
'solr.core.collection1.shard2.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7fcf1a
[junit4] 2> 418342 INFO (qtp22191139-2157) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 418342 INFO (qtp22191139-2157) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001/cores/collection1_shard2_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001/shard-3-001/cores/collection1_shard2_replica_n1/data/]
[junit4] 2> 418345 INFO (qtp22191139-2157) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=21.1943359375,
floorSegmentMB=0.580078125, forceMergeDeletesPctAllowed=1.2157814188153537,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.706646999988747
[junit4] 2> 418349 WARN (qtp22191139-2157) [ ] 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> 418414 INFO (qtp22191139-2157) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 418414 INFO (qtp22191139-2157) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 418416 INFO (qtp22191139-2157) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 418416 INFO (qtp22191139-2157) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 418417 INFO (qtp22191139-2157) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@6d8e6f
[junit4] 2> 418418 INFO (qtp22191139-2157) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@15e3ca3[collection1_shard2_replica_n1] main]
[junit4] 2> 418418 INFO (qtp22191139-2157) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 418418 INFO (qtp22191139-2157) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 418419 INFO (qtp22191139-2157) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 418420 INFO (searcherExecutor-677-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher
Searcher@15e3ca3[collection1_shard2_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 418420 INFO (qtp22191139-2157) [ ] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1573341321951707136
[junit4] 2> 418423 INFO (qtp22191139-2157) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard2_replica_n1
[junit4] 2> 418423 INFO (updateExecutor-329-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 418423 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 418423 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 418423 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard2_replica_n1]
[junit4] 2> 418423 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 418423 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard2_replica_n1] as recovering, leader is
[http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/] and I am
[http://127.0.0.1:45497/_/jt/collection1_shard2_replica_n1/]
[junit4] 2> 418424 INFO (qtp22191139-2157) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1171
[junit4] 2> 418425 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:43871/_/jt]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard2_replica_n0&nodeName=127.0.0.1:45497__%252Fjt&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 418425 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51736,localport=43871], receiveBufferSize:531000
[junit4] 2> 418425 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34880], receiveBufferSize=530904
[junit4] 2> 418426 INFO (qtp218205-2124) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:45497__%252Fjt&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
status=0 QTime=1183
[junit4] 2> 418426 INFO (qtp23563170-2086) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 418426 INFO (qtp23563170-2086) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_n0,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:45497__%2Fjt,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_n1","base_url":"http://127.0.0.1:45497/_/jt","node_name":"127.0.0.1:45497__%2Fjt","state":"down","type":"NRT"}
[junit4] 2> 418429 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 418429 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait
30000 for each attempt
[junit4] 2> 418429 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30000
[junit4] 2> 418526 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 418526 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 418526 INFO (zkCallback-332-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 419244 INFO
(OverseerCollectionConfigSetProcessor-98333831785807876-127.0.0.1:42269__%2Fjt-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> 419426 INFO (qtp23563170-2086) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_n0,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:45497__%2Fjt,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_n1","base_url":"http://127.0.0.1:45497/_/jt","node_name":"127.0.0.1:45497__%2Fjt","state":"recovering","type":"NRT"}
[junit4] 2> 419426 INFO (qtp23563170-2086) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 419426 INFO (qtp23563170-2086) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:45497__%252Fjt&onlyIfLeaderActive=true&core=collection1_shard2_replica_n0&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 419926 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/] -
recoveringAfterStartup=[true]
[junit4] 2> 419927 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n1
url=http://127.0.0.1:45497/_/jt START
replicas=[http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/]
nUpdates=100
[junit4] 2> 419927 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51740,localport=43871], receiveBufferSize:531000
[junit4] 2> 419927 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34884], receiveBufferSize=530904
[junit4] 2> 419929 INFO (qtp23563170-2087) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:1.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 419929 INFO (qtp23563170-2087) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n0] webapp=/_/jt path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 419931 INFO (recoveryExecutor-330-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> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 419931 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 420033 INFO (zkCallback-332-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 420033 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 420033 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 420430 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 420431 INFO (qtp8790374-2039) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573341324060393472,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 420432 INFO (qtp8790374-2039) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 420432 INFO (qtp8790374-2039) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 420432 INFO (qtp8790374-2039) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [control_collection_shard1_replica_n1]
webapp=/_/jt path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 0
[junit4] 2> 420433 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51746,localport=43871], receiveBufferSize:531000
[junit4] 2> 420433 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34890], receiveBufferSize=530904
[junit4] 2> 420436 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=47452,localport=35917], receiveBufferSize:531000
[junit4] 2> 420437 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36123,localport=45734], receiveBufferSize=530904
[junit4] 2> 420437 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51754,localport=43871], receiveBufferSize:531000
[junit4] 2> 420437 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=39640,localport=45497], receiveBufferSize:531000
[junit4] 2> 420438 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34900], receiveBufferSize=530904
[junit4] 2> 420438 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39155,localport=52126], receiveBufferSize=530904
[junit4] 2> 420437 INFO (qtp218205-2119) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573341324066684928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 420442 INFO (qtp218205-2119) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 420443 INFO (qtp23563170-2090) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573341324072976384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 420443 INFO (qtp22191139-2159) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573341324072976384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 420443 INFO (qtp23563170-2090) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 420444 INFO (qtp22191139-2159) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 420444 INFO (qtp218205-2119) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 420444 INFO (qtp23563170-2090) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 420444 INFO (qtp22191139-2159) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 420444 INFO (qtp23563170-2090) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n0]
webapp=/_/jt path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 420444 INFO (qtp218205-2119) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n0]
webapp=/_/jt path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 6
[junit4] 2> 420447 INFO (qtp22191139-2159) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/_/jt path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43871/_/jt/collection1_shard2_replica_n0/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 4
[junit4] 2> 420448 INFO (qtp23563170-2092) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n0]
webapp=/_/jt path=/update
params={_stateVer_=collection1:10&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 12
[junit4] 2> 420449 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51762,localport=43871], receiveBufferSize:531000
[junit4] 2> 420449 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34906], receiveBufferSize=530904
[junit4] 2> 420450 INFO (qtp23563170-2087) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n0] webapp=/_/jt path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 420450 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=39650,localport=45497], receiveBufferSize:531000
[junit4] 2> 420451 INFO (SocketProxy-Acceptor-45497) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=39155,localport=52134], receiveBufferSize=530904
[junit4] 2> 420452 INFO (qtp22191139-2157) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n1] webapp=/_/jt path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 420453 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=47472,localport=35917], receiveBufferSize:531000
[junit4] 2> 420453 INFO (SocketProxy-Acceptor-35917) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=36123,localport=45754], receiveBufferSize=530904
[junit4] 2> 420454 INFO (qtp218205-2124) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_n0] webapp=/_/jt path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 422456 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=51774,localport=43871], receiveBufferSize:531000
[junit4] 2> 422456 INFO (SocketProxy-Acceptor-43871) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=34421,localport=34918], receiveBufferSize=530904
[junit4] 2> 422457 INFO (qtp23563170-2092) [ ]
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&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 422458 INFO (OverseerThreadFactory-641-thread-5) [ ]
o.a.s.c.CreateCollectionCmd Create collection c8n_1x3_lf
[junit4] 2> 422561 INFO (qtp22191139-2158) [ ]
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> 422563 INFO (qtp218205-2125) [ ]
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> 422564 INFO (qtp23563170-2091) [ ]
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> 422666 INFO (zkCallback-332-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/c8n_1x3_lf/state.json] for collection [c8n_1x3_lf] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 422666 INFO (zkCallback-326-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/c8n_1x3_lf/state.json] for collection [c8n_1x3_lf] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 422666 INFO (zkCallback-320-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/c8n_1x3_lf/state.json] for collection [c8n_1x3_lf] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 423572 INFO (qtp218205-2125) [ ] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.1.0
[junit4] 2> 423572 INFO (qtp22191139-2158) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 423572 INFO (qtp23563170-2091) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.1.0
[junit4] 2> 423579 INFO (qtp218205-2125) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n3] Schema name=test
[junit4] 2> 423581 INFO (qtp22191139-2158) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n1] Schema name=test
[junit4] 2> 423582 INFO (qtp23563170-2091) [ ] o.a.s.s.IndexSchema
[c8n_1x3_lf_shard1_replica_n2] Schema name=test
[junit4] 2> 423664 INFO (qtp218205-2125) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 423697 INFO (qtp23563170-2091) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 423704 INFO (qtp22191139-2158) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 423712 INFO (qtp218205-2125) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'c8n_1x3_lf_shard1_replica_n3' using configuration from
collection c8n_1x3_lf, trusted=true
[junit4] 2> 423713 INFO (qtp218205-2125) [ ] o.a.s.m.r.SolrJmxReport
[...truncated too long message...]
as been closed
[junit4] 2> 557212 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false]
[junit4] 2> 557212 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.SolrCore [c8n_1x3_lf_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@c1f88
[junit4] 2> 557213 WARN (recoveryExecutor-330-thread-1) [ ]
o.a.s.c.RecoveryStrategy Stopping recovery for
core=[c8n_1x3_lf_shard1_replica_n1] coreNodeName=[core_node1]
[junit4] 2> 557216 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.c8n_1x3_lf.shard1.replica_n1, tag=794504
[junit4] 2> 557219 INFO (recoveryExecutor-330-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.c8n_1x3_lf.shard1.leader, tag=794504
[junit4] 2> 557219 WARN (updateExecutor-329-thread-2) [ ]
o.a.s.u.DefaultSolrCoreState Skipping recovery because Solr is shutdown
[junit4] 2> 557220 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.Overseer Overseer
(id=98333831785807887-127.0.0.1:45497__%2Fjt-n_0000000003) closing
[junit4] 2> 557220 INFO
(OverseerStateUpdate-98333831785807887-127.0.0.1:45497__%2Fjt-n_0000000003) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45497__%2Fjt
[junit4] 2> 557221 WARN (zkCallback-332-thread-5) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 557222 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@1bf6797{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 557222 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@258999{/_/jt,null,UNAVAILABLE}
[junit4] 2> 557222 ERROR
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
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> 557222 INFO
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34251 34251
[junit4] 2> 562253 INFO (Thread-468) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:34251 34251
[junit4] 2> 562253 WARN (Thread-468) [ ] 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>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2> 3 /solr/collections/c8n_1x3_lf/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98333831785807881-127.0.0.1:43871__%2Fjt-n_0000000001
[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> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 562253 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SocketProxy Closing 3 connections to: http://127.0.0.1:42269/_/jt,
target: http://127.0.0.1:36381/_/jt
[junit4] 2> 562253 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SocketProxy Closing 1 connections to: http://127.0.0.1:45497/_/jt,
target: http://127.0.0.1:39155/_/jt
[junit4] 2> 562253 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SocketProxy Closing 7 connections to: http://127.0.0.1:43871/_/jt,
target: http://127.0.0.1:34421/_/jt
[junit4] 2> 562253 WARN
(TEST-LeaderFailoverAfterPartitionTest.test-seed#[5D663409B0D3410B]) [ ]
o.a.s.c.SocketProxy Closing 0 connections to: http://127.0.0.1:35917/_/jt,
target: http://127.0.0.1:36123/_/jt
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=LeaderFailoverAfterPartitionTest -Dtests.method=test
-Dtests.seed=5D663409B0D3410B -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=zh-SG -Dtests.timezone=Europe/Vienna -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 157s J2 | LeaderFailoverAfterPartitionTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected 2 of 3
replicas to be active but only found 1;
[core_node3:{"core":"c8n_1x3_lf_shard1_replica_n2","base_url":"http://127.0.0.1:43871/_/jt","node_name":"127.0.0.1:43871__%2Fjt","state":"active","type":"NRT","leader":"true"}];
clusterState: DocCollection(c8n_1x3_lf//collections/c8n_1x3_lf/state.json/17)={
[junit4] > "pullReplicas":"0",
[junit4] > "replicationFactor":"1",
[junit4] > "shards":{"shard1":{
[junit4] > "range":"80000000-7fffffff",
[junit4] > "state":"active",
[junit4] > "replicas":{
[junit4] > "core_node1":{
[junit4] > "state":"down",
[junit4] > "base_url":"http://127.0.0.1:45497/_/jt";,
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n1",
[junit4] > "node_name":"127.0.0.1:45497__%2Fjt",
[junit4] > "type":"NRT"},
[junit4] > "core_node2":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n3",
[junit4] > "base_url":"http://127.0.0.1:35917/_/jt";,
[junit4] > "node_name":"127.0.0.1:35917__%2Fjt",
[junit4] > "state":"down",
[junit4] > "type":"NRT"},
[junit4] > "core_node3":{
[junit4] > "core":"c8n_1x3_lf_shard1_replica_n2",
[junit4] > "base_url":"http://127.0.0.1:43871/_/jt";,
[junit4] > "node_name":"127.0.0.1:43871__%2Fjt",
[junit4] > "state":"active",
[junit4] > "type":"NRT",
[junit4] > "leader":"true"}}}},
[junit4] > "router":{"name":"compositeId"},
[junit4] > "maxShardsPerNode":"1",
[junit4] > "autoAddReplicas":"false",
[junit4] > "nrtReplicas":"3",
[junit4] > "tlogReplicas":"0"}
[junit4] > at
__randomizedtesting.SeedInfo.seed([5D663409B0D3410B:D5320BD31E2F2CF3]: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:985)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[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/J2/temp/solr.cloud.LeaderFailoverAfterPartitionTest_5D663409B0D3410B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{range_facet_l_dv=Lucene50(blocksize=128),
multiDefault=PostingsFormat(name=Asserting),
a_t=PostingsFormat(name=MockRandom),
intDefault=PostingsFormat(name=MockRandom),
id_i1=PostingsFormat(name=Asserting), id=Lucene50(blocksize=128),
range_facet_i_dv=PostingsFormat(name=MockRandom),
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
range_facet_l=PostingsFormat(name=MockRandom),
timestamp=PostingsFormat(name=MockRandom)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct),
_version_=DocValuesFormat(name=Memory),
intDefault=DocValuesFormat(name=Memory), id_i1=DocValuesFormat(name=Lucene70),
range_facet_i_dv=DocValuesFormat(name=Memory),
intDvoDefault=DocValuesFormat(name=Asserting),
range_facet_l=DocValuesFormat(name=Memory),
timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1809,
maxMBSortInHeap=5.851336957258596, sim=RandomSimilarity(queryNorm=true): {},
locale=zh-SG, timezone=Europe/Vienna
[junit4] 2> NOTE: Linux 4.10.0-26-generic i386/Oracle Corporation 9
(32-bit)/cpus=8,threads=1,free=175484840,total=343932928
[junit4] 2> NOTE: All tests run in this JVM:
[TestDelegationWithHadoopAuth, TestFieldCacheSortRandom,
PathHierarchyTokenizerFactoryTest,
ChaosMonkeyNothingIsSafeWithPullReplicasTest, PreAnalyzedFieldTest,
AutoCommitTest, DirectUpdateHandlerOptimizeTest,
AtomicUpdateProcessorFactoryTest, TestFieldCache, SpellPossibilityIteratorTest,
TestTolerantUpdateProcessorCloud, TestLMJelinekMercerSimilarityFactory,
SearchHandlerTest, TestIndexSearcher, TestRestoreCore, TestSurroundQueryParser,
BaseCdcrDistributedZkTest, SolrInfoBeanTest, TestRawResponseWriter,
TimeZoneUtilsTest, TestLegacyFieldCache, ForceLeaderTest,
TestSolrCloudWithKerberosAlt, DistribCursorPagingTest,
LeaderFailoverAfterPartitionTest]
[junit4] Completed [110/728 (1!)] on J2 in 156.61s, 1 test, 1 failure <<<
FAILURES!
[...truncated 38575 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
