Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/354/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.cloud.TestCloudRecovery.corruptedLogTest

Error Message:
Timeout waiting for all live and active

Stack Trace:
java.lang.AssertionError: Timeout waiting for all live and active
        at 
__randomizedtesting.SeedInfo.seed([60BE15BA89FE48A0:E3C84A485F874601]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.TestCloudRecovery.corruptedLogTest(TestCloudRecovery.java:184)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11976 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestCloudRecovery
   [junit4]   2> 835836 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/init-core-data-001
   [junit4]   2> 835837 WARN  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 835837 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 835838 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 835839 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001
   [junit4]   2> 835839 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 835839 INFO  (Thread-2153) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 835839 INFO  (Thread-2153) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 835841 ERROR (Thread-2153) [    ] 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> 835939 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:33021
   [junit4]   2> 835955 INFO  (jetty-launcher-940-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 835955 INFO  (jetty-launcher-940-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 835959 INFO  (jetty-launcher-940-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@12f8744{/solr,null,AVAILABLE}
   [junit4]   2> 835959 INFO  (jetty-launcher-940-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@31b992{/solr,null,AVAILABLE}
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@def856{SSL,[ssl, 
http/1.1]}{127.0.0.1:34285}
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-1) [    ] 
o.e.j.s.Server Started @836900ms
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34285}
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@110ede6{SSL,[ssl, 
http/1.1]}{127.0.0.1:46827}
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-2) [    ] 
o.e.j.s.Server Started @836900ms
   [junit4]   2> 835960 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46827}
   [junit4]   2> 835960 ERROR (jetty-launcher-940-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 835961 ERROR (jetty-launcher-940-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-03T12:41:07.984Z
   [junit4]   2> 835961 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-03T12:41:07.984Z
   [junit4]   2> 835965 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 835965 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 835980 INFO  (jetty-launcher-940-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33021/solr
   [junit4]   2> 835980 INFO  (jetty-launcher-940-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33021/solr
   [junit4]   2> 835984 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15e47c246170003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 836082 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 836082 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:46827_solr
   [junit4]   2> 836082 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 836083 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.Overseer Overseer 
(id=98595141573869573-127.0.0.1:46827_solr-n_0000000000) starting
   [junit4]   2> 836083 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34285_solr
   [junit4]   2> 836084 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 836084 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 836087 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46827_solr
   [junit4]   2> 836088 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 836088 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 836204 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46827.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836205 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34285.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836220 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34285.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836220 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34285.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836221 INFO  (jetty-launcher-940-thread-1) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node1/.
   [junit4]   2> 836223 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46827.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836224 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46827.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 836225 INFO  (jetty-launcher-940-thread-2) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node2/.
   [junit4]   2> 836273 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 836274 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33021/solr ready
   [junit4]   2> 836339 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
pullReplicas=0&replicationFactor=0&collection.configName=config&maxShardsPerNode=2&name=collection1&nrtReplicas=0&action=CREATE&numShards=2&tlogReplicas=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 836342 INFO  
(OverseerThreadFactory-3124-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 836451 INFO  
(OverseerStateUpdate-98595141573869573-127.0.0.1:46827_solr-n_0000000000) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_t1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:34285/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 836455 INFO  
(OverseerStateUpdate-98595141573869573-127.0.0.1:46827_solr-n_0000000000) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_t2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:46827/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 836459 INFO  
(OverseerStateUpdate-98595141573869573-127.0.0.1:46827_solr-n_0000000000) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection1_shard2_replica_t4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:34285/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 836462 INFO  
(OverseerStateUpdate-98595141573869573-127.0.0.1:46827_solr-n_0000000000) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection1_shard2_replica_t6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:46827/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 836673 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=config&newCollection=true&name=collection1_shard2_replica_t4&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 836673 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard1_replica_t1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 836674 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 836751 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=config&newCollection=true&name=collection1_shard1_replica_t2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 836751 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 836754 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=collection1_shard2_replica_t6&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 836860 INFO  
(zkCallback-951-thread-2-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 836860 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 836860 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 836861 INFO  
(zkCallback-952-thread-2-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 837693 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 837693 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 837707 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.s.IndexSchema [collection1_shard1_replica_t1] Schema name=minimal
   [junit4]   2> 837707 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.s.IndexSchema [collection1_shard2_replica_t4] Schema name=minimal
   [junit4]   2> 837716 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 837716 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 837716 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_t4' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 837716 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_t1' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 837717 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34285.solr.core.collection1.shard2.replica_t4' (registry 
'solr.core.collection1.shard2.replica_t4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 837717 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34285.solr.core.collection1.shard1.replica_t1' (registry 
'solr.core.collection1.shard1.replica_t1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 837717 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 837717 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 837717 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.SolrCore [[collection1_shard2_replica_t4] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node1/collection1_shard2_replica_t4],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node1/./collection1_shard2_replica_t4/data/]
   [junit4]   2> 837717 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.SolrCore [[collection1_shard1_replica_t1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node1/collection1_shard1_replica_t1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node1/./collection1_shard1_replica_t1/data/]
   [junit4]   2> 837763 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 837768 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 837769 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.s.IndexSchema [collection1_shard2_replica_t6] Schema name=minimal
   [junit4]   2> 837772 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 837772 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_t6' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 837772 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46827.solr.core.collection1.shard2.replica_t6' (registry 
'solr.core.collection1.shard2.replica_t6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 837772 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 837772 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.SolrCore [[collection1_shard2_replica_t6] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node2/collection1_shard2_replica_t6],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node2/./collection1_shard2_replica_t6/data/]
   [junit4]   2> 837776 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.s.IndexSchema [collection1_shard1_replica_t2] Schema name=minimal
   [junit4]   2> 837778 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 837778 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_t2' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 837779 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46827.solr.core.collection1.shard1.replica_t2' (registry 
'solr.core.collection1.shard1.replica_t2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@c37a30
   [junit4]   2> 837779 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 837779 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.SolrCore [[collection1_shard1_replica_t2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node2/collection1_shard1_replica_t2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001/tempDir-001/node2/./collection1_shard1_replica_t2/data/]
   [junit4]   2> 837787 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 837787 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 837787 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 837787 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 837788 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 837788 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 837788 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 837788 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 837790 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17867b8[collection1_shard2_replica_t4] main]
   [junit4]   2> 837790 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@10f95c7[collection1_shard1_replica_t1] main]
   [junit4]   2> 837791 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 837791 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 837792 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 837792 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 837792 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 837792 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 837793 INFO  
(searcherExecutor-3129-thread-1-processing-n:127.0.0.1:34285_solr 
x:collection1_shard2_replica_t4 s:shard2 c:collection1 r:core_node7) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.SolrCore 
[collection1_shard2_replica_t4] Registered new searcher 
Searcher@17867b8[collection1_shard2_replica_t4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 837793 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577522267229782016
   [junit4]   2> 837794 INFO  
(searcherExecutor-3130-thread-1-processing-n:127.0.0.1:34285_solr 
x:collection1_shard1_replica_t1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:34285_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_t1] o.a.s.c.SolrCore 
[collection1_shard1_replica_t1] Registered new searcher 
Searcher@10f95c7[collection1_shard1_replica_t1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 837794 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577522267230830592
   [junit4]   2> 837798 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 837798 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 837835 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 837835 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 837836 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 837836 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 837838 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@235c67[collection1_shard2_replica_t6] main]
   [junit4]   2> 837839 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 837840 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 837840 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 837842 INFO  
(searcherExecutor-3131-thread-1-processing-n:127.0.0.1:46827_solr 
x:collection1_shard2_replica_t6 s:shard2 c:collection1 r:core_node8) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.c.SolrCore 
[collection1_shard2_replica_t6] Registered new searcher 
Searcher@235c67[collection1_shard2_replica_t6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 837842 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577522267281162240
   [junit4]   2> 837846 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 837846 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 837847 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 837847 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 837848 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@33909f[collection1_shard1_replica_t2] main]
   [junit4]   2> 837849 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 837849 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 837850 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 837851 INFO  
(searcherExecutor-3134-thread-1-processing-n:127.0.0.1:46827_solr 
x:collection1_shard1_replica_t2 s:shard1 c:collection1 r:core_node5) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.c.SolrCore 
[collection1_shard1_replica_t2] Registered new searcher 
Searcher@33909f[collection1_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 837851 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1577522267290599424
   [junit4]   2> 837900 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 837900 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 837901 INFO  
(zkCallback-952-thread-2-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 837900 INFO  
(zkCallback-951-thread-2-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838298 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 838298 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 838298 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/
   [junit4]   2> 838299 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 838299 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 838299 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:34285/solr/collection1_shard1_replica_t1/
   [junit4]   2> 838299 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_t4 
url=https://127.0.0.1:34285/solr START 
replicas=[https://127.0.0.1:46827/solr/collection1_shard2_replica_t6/] 
nUpdates=100
   [junit4]   2> 838299 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_t1 
url=https://127.0.0.1:34285/solr START 
replicas=[https://127.0.0.1:46827/solr/collection1_shard1_replica_t2/] 
nUpdates=100
   [junit4]   2> 838304 INFO  (qtp13463513-7618) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.S.Request [collection1_shard1_replica_t2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 838304 INFO  (qtp13463513-7612) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.S.Request [collection1_shard2_replica_t6]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 838599 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_t4 
url=https://127.0.0.1:34285/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 838599 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 838599 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 838599 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 838599 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_t1 
url=https://127.0.0.1:34285/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 838599 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 838599 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 838599 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 838600 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ZkController collection1_shard1_replica_t1 stopping background 
replication from leader
   [junit4]   2> 838600 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ZkController collection1_shard2_replica_t4 stopping background 
replication from leader
   [junit4]   2> 838602 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:34285/solr/collection1_shard1_replica_t1/ shard1
   [junit4]   2> 838602 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/ shard2
   [junit4]   2> 838705 INFO  
(zkCallback-951-thread-2-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838705 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838705 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838705 INFO  
(zkCallback-952-thread-2-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838753 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 838753 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 838755 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=config&newCollection=true&name=collection1_shard2_replica_t4&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=2082
   [junit4]   2> 838755 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard1_replica_t1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=2082
   [junit4]   2> 838845 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.ZkController collection1_shard2_replica_t6 starting background 
replication from leader
   [junit4]   2> 838846 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll 
interval: 00:00:03
   [junit4]   2> 838849 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 838849 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Replica core_node7 is 
leader but it's state is down, skipping replication
   [junit4]   2> 838851 INFO  (qtp13463513-7611) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=config&newCollection=true&name=collection1_shard2_replica_t6&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=2096
   [junit4]   2> 838854 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.ZkController collection1_shard1_replica_t2 starting background 
replication from leader
   [junit4]   2> 838854 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll 
interval: 00:00:03
   [junit4]   2> 838855 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 838856 INFO  (qtp13463513-7620) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=config&newCollection=true&name=collection1_shard1_replica_t2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=2104
   [junit4]   2> 838859 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 838957 INFO  
(zkCallback-952-thread-2-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838957 INFO  
(zkCallback-951-thread-2-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838957 INFO  
(zkCallback-952-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 838957 INFO  
(zkCallback-951-thread-1-processing-n:127.0.0.1:46827_solr) 
[n:127.0.0.1:46827_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 839859 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={pullReplicas=0&replicationFactor=0&collection.configName=config&maxShardsPerNode=2&name=collection1&nrtReplicas=0&action=CREATE&numShards=2&tlogReplicas=2&wt=javabin&version=2}
 status=0 QTime=3520
   [junit4]   2> 839860 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):30
   [junit4]   2> 839861 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 839925 INFO  
(TEST-TestCloudRecovery.leaderRecoverFromLogOnStartupTest-seed#[60BE15BA89FE48A0])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting leaderRecoverFromLogOnStartupTest
   [junit4]   2> 839941 INFO  (qtp13463513-7612) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_t6]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&_version_=-1577522269469540352&distrib.from=https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1577522269469540352)} 0 1
   [junit4]   2> 839947 INFO  (qtp13463513-7614) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&_version_=-1577522269482123264&distrib.from=https://127.0.0.1:34285/solr/collection1_shard1_replica_t1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1577522269482123264)} 0 1
   [junit4]   2> 839948 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1577522269482123264)} 0 8
   [junit4]   2> 839949 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_t4]  
webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1577522269469540352)} 0 21
   [junit4]   2> 839956 INFO  (qtp13463513-7691) [n:127.0.0.1:46827_solr 
c:collection1 s:shard1 r:core_node5 x:collection1_shard1_replica_t2] 
o.a.s.u.TestInjection Start waiting for replica in sync with leader
   [junit4]   2> 839956 INFO  (qtp13463513-7690) [n:127.0.0.1:46827_solr 
c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_t6] 
o.a.s.u.TestInjection Start waiting for replica in sync with leader
   [junit4]   2> 839958 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1577522269499949056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 839959 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 839959 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 839960 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_t4]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 839964 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1577522269506240512,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 839964 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 839965 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 839965 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_t1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 839966 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=1
   [junit4]   2> 839966 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 840346 INFO  
(OverseerCollectionConfigSetProcessor-98595141573869573-127.0.0.1:46827_solr-n_0000000000)
 [n:127.0.0.1:46827_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 840473 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 840473 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 840980 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 840980 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 841372 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:34285/solr/collection1_shard1_replica_t1/
   [junit4]   2> 841376 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 841377 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 841377 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 841377 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 841377 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 841487 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 841487 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 841849 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Last replication failed, 
so I'll force replication
   [junit4]   2> 841849 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/
   [junit4]   2> 841851 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher New index in Master. 
Deleting mine...
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 841852 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.u.SolrIndexWriter Calling setCommitData 
with IW:org.apache.solr.update.SolrIndexWriter@1c9e147 commitCommandVersion:0
   [junit4]   2> 841863 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1318a37[collection1_shard2_replica_t6] main]
   [junit4]   2> 841864 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 841866 INFO  
(searcherExecutor-3131-thread-1-processing-n:127.0.0.1:46827_solr 
x:collection1_shard2_replica_t6 s:shard2 c:collection1 r:core_node8) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.c.SolrCore 
[collection1_shard2_replica_t6] Registered new searcher 
Searcher@1318a37[collection1_shard2_replica_t6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 841993 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 841993 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 842497 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 842497 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 843004 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 843004 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 843511 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 843511 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 844018 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 844019 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 844374 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 844375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 844375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 844375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 844375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 844526 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 844526 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 844849 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 844850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 844850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 844850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 844850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's version: 
1504442473875
   [junit4]   2> 845035 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 845036 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 845540 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 845540 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 846047 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 846047 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 846556 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 846557 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 847061 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 847061 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 847374 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 847375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 847375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 847375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 847375 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 847569 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 847569 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 847850 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 847850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 847850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 847850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 847850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's version: 
1504442473875
   [junit4]   2> 848077 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 848078 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 848582 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 848582 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 849089 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 849090 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 849598 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 849598 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 850104 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 850104 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 850373 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 850373 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 850373 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 850373 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 850374 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 850610 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 850611 INFO  (qtp32223966-7617) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 850850 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 850851 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 850851 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 850851 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 850851 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's version: 
1504442473875
   [junit4]   2> 851118 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 851118 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 851622 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 851622 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 852128 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 852130 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 852636 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 852637 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 853141 INFO  (qtp32223966-7700) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 853141 INFO  (qtp32223966-7673) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 853373 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 853374 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 853374 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 853374 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 853374 INFO  (indexFetcher-3152-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard1 r:core_node5 
x:collection1_shard1_replica_t2] o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 853646 INFO  (qtp32223966-7619) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 853647 INFO  (qtp32223966-7626) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 853849 INFO  (qtp32223966-7624) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 853850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 853850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 853850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 853850 INFO  (indexFetcher-3150-thread-1) 
[n:127.0.0.1:46827_solr c:collection1 s:shard2 r:core_node8 
x:collection1_shard2_replica_t6] o.a.s.h.IndexFetcher Slave's version: 
1504442473875
   [junit4]   2> 854153 INFO  (qtp32223966-7623) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 854161 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 854660 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_t1] 
o.a.s.c.S.Request [collection1_shard1_replica_t1]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 854668 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr 
c:collection1 s:shard2 r:core_node7 x:collection1_shard2_replica_t4] 
o.a.s.c.S.Request [collection1_shard2_replica_t4]  webapp=/solr 
path=/replication params={qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 855166 INFO  (qtp32223966-7613) [n:127.0.0.1:34285_solr c:colle

[...truncated too long message...]

49)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: CoreContainer 
is closed
   [junit4]   2>        at 
org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1143)
   [junit4]   2>        at 
org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1110)
   [junit4]   2>        at 
org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1066)
   [junit4]   2>        ... 7 more
   [junit4]   2> 
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.SyncStrategy 
https://127.0.0.1:34285/solr/collection1_shard2_replica_t4/ has no replicas
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.SolrCore 
[collection1_shard2_replica_t4]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@fd0818
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.core.collection1.shard2.replica_t4, tag=16582680
   [junit4]   2> 1003235 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@fc1a7e: rootName = 
solr_34285, domain = solr.core.collection1.shard2.replica_t4, service url = 
null, agent id = null] for registry solr.core.collection1.shard2.replica_t4 / 
com.codahale.metrics.MetricRegistry@1880f20
   [junit4]   2> 1003248 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.collection1.shard2.leader, tag=16582680
   [junit4]   2> 1003248 WARN  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.RecoveryStrategy Stopping recovery for 
core=[collection1_shard2_replica_t4] coreNodeName=[core_node7]
   [junit4]   2> 1003250 INFO  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 1003251 ERROR 
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.ShardLeaderElectionContext There was a 
problem trying to register as the leader:java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.solr.cloud.ShardLeaderElectionContext.checkLIR(ElectionContext.java:529)
   [junit4]   2>        at 
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:431)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
   [junit4]   2>        at 
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1004627 ERROR 
(recoveryExecutor-981-thread-1-processing-n:127.0.0.1:34285_solr 
x:collection1_shard2_replica_t4 s:shard2 c:collection1 r:core_node7) 
[n:127.0.0.1:34285_solr c:collection1 s:shard2 r:core_node7 
x:collection1_shard2_replica_t4] o.a.s.c.RecoveryStrategy SolrCore not found - 
cannot recover:collection1_shard2_replica_t4
   [junit4]   2> 1004628 WARN  
(zkCallback-983-thread-1-processing-n:127.0.0.1:34285_solr) 
[n:127.0.0.1:34285_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1004630 INFO  (jetty-closer-941-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@13fbda3{/solr,null,UNAVAILABLE}
   [junit4]   2> 1004630 ERROR 
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1004630 INFO  
(SUITE-TestCloudRecovery-seed#[60BE15BA89FE48A0]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33021 33021
   [junit4]   2> 1005204 INFO  (Thread-2153) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:33021 33021
   [junit4]   2> 1005204 WARN  (Thread-2153) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/aliases.json
   [junit4]   2>        6       /solr/security.json
   [junit4]   2>        6       /solr/configs/config
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        132     /solr/collections/collection1/state.json
   [junit4]   2>        7       /solr/clusterstate.json
   [junit4]   2>        7       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       /solr/collections
   [junit4]   2>        5       /solr/overseer/collection-queue-work
   [junit4]   2>        4       /solr/overseer/queue
   [junit4]   2>        4       /solr/overseer/queue-work
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudRecovery_60BE15BA89FE48A0-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=mk, timezone=GMT
   [junit4]   2> NOTE: Linux 4.10.0-33-generic i386/Oracle Corporation 
1.8.0_144 (32-bit)/cpus=8,threads=1,free=244761016,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: 
[SolrCoreCheckLockOnStartupTest, V2ApiIntegrationTest, PrimitiveFieldTypeTest, 
TestExclusionRuleCollectionAccess, UtilsToolTest, 
CloudExitableDirectoryReaderTest, TestConfigSetImmutable, 
TestUniqueKeyFieldResource, InfixSuggestersTest, TimeZoneUtilsTest, AssignTest, 
SimplePostToolTest, TestDFRSimilarityFactory, TestMultiWordSynonyms, 
StatsComponentTest, HttpPartitionTest, TestLMJelinekMercerSimilarityFactory, 
TestPrepRecovery, CdcrUpdateLogTest, PolyFieldTest, 
TestSubQueryTransformerCrossCore, TestRuleBasedAuthorizationPlugin, 
TestXmlQParserPlugin, TermVectorComponentDistributedTest, TestLRUStatsCache, 
CollectionTooManyReplicasTest, DirectUpdateHandlerOptimizeTest, TestRecovery, 
DistributedFacetPivotLargeTest, DocExpirationUpdateProcessorFactoryTest, 
StressHdfsTest, TestJmxIntegration, TestCollectionAPI, SortByFunctionTest, 
SuggesterFSTTest, PKIAuthenticationIntegrationTest, 
HdfsRestartWhileUpdatingTest, TestSchemaSimilarityResource, 
TestCorePropertiesReload, FullSolrCloudDistribCmdsTest, TestLFUCache, 
ShowFileRequestHandlerTest, BigEndianAscendingWordSerializerTest, 
OverseerCollectionConfigSetProcessorTest, QueryResultKeyTest, TestNRTOpen, 
TestPolicyCloud, SolrCLIZkUtilsTest, TestSchemaManager, 
HighlighterMaxOffsetTest, TestFoldingMultitermQuery, 
TestPerFieldSimilarityWithDefaultOverride, TestSSLRandomization, 
SolrMetricManagerTest, TestFastWriter, TestFieldCacheSortRandom, 
TestEmbeddedSolrServerSchemaAPI, AutoCommitTest, ClusterStateTest, 
DateMathParserTest, CopyFieldTest, MetricsHandlerTest, TestUtils, 
SharedFSAutoReplicaFailoverTest, TestTrie, TestReload, TestFieldCache, 
VMParamsZkACLAndCredentialsProvidersTest, UniqFieldsUpdateProcessorFactoryTest, 
TestCollectionAPIs, MoveReplicaHDFSUlogDirTest, TestDistribIDF, 
HdfsChaosMonkeyNothingIsSafeTest, TestRTimerTree, TestSurroundQueryParser, 
TestManagedSchemaAPI, ChaosMonkeySafeLeaderWithPullReplicasTest, 
TestPivotHelperCode, DistributedDebugComponentTest, 
AtomicUpdateProcessorFactoryTest, ResponseLogComponentTest, 
TestLegacyNumericRangeQueryBuilder, HdfsDirectoryTest, RequiredFieldsTest, 
TestSolrDeletionPolicy2, TestSizeLimitedDistributedMap, 
SpatialHeatmapFacetsTest, HdfsTlogReplayBufferedWhileIndexingTest, 
RankQueryTest, NotRequiredUniqueKeyTest, SolrTestCaseJ4Test, TestCloudRecovery]
   [junit4] Completed [225/731 (1!)] on J2 in 169.38s, 2 tests, 1 failure <<< 
FAILURES!

[...truncated 43680 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to