Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/177/

2 tests failed.
FAILED:  org.apache.solr.cloud.LeaderElectionContextKeyTest.test

Error Message:


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


FAILED:  org.apache.solr.cloud.TestTlogReplica.testOnlyLeaderIndexes

Error Message:
expected:<2> but was:<4>

Stack Trace:
java.lang.AssertionError: expected:<2> but was:<4>
        at 
__randomizedtesting.SeedInfo.seed([E5C5B346BFC8A7D6:F9C4CECBCA6DD945]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.TestTlogReplica.assertCopyOverOldUpdates(TestTlogReplica.java:909)
        at 
org.apache.solr.cloud.TestTlogReplica.testOnlyLeaderIndexes(TestTlogReplica.java:501)
        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 12174 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestTlogReplica
   [junit4]   2> 1721348 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/init-core-data-001
   [junit4]   2> 1721361 WARN  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1721361 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1721363 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1721398 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001
   [junit4]   2> 1721398 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1721417 INFO  (Thread-1012) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1721417 INFO  (Thread-1012) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1721551 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42857
   [junit4]   2> 1721587 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to 
java.io.IOException: ZooKeeperServer not running
   [junit4]   2> 1721624 ERROR (Thread-1012) [    ] 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> 1722809 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1722821 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1722877 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@832becd{/solr,null,AVAILABLE}
   [junit4]   2> 1722878 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@22c5c74{SSL,[ssl, 
http/1.1]}{127.0.0.1:38368}
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.e.j.s.Server Started @1735598ms
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38368}
   [junit4]   2> 1722879 ERROR (jetty-launcher-2526-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1722879 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-07T07:56:36.475Z
   [junit4]   2> 1722898 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@54441756{/solr,null,AVAILABLE}
   [junit4]   2> 1722898 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@32cc9e10{SSL,[ssl, 
http/1.1]}{127.0.0.1:39418}
   [junit4]   2> 1722898 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.e.j.s.Server Started @1735617ms
   [junit4]   2> 1722898 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39418}
   [junit4]   2> 1722899 ERROR (jetty-launcher-2526-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1722899 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.1.0
   [junit4]   2> 1722899 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1722899 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1722899 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-10-07T07:56:36.495Z
   [junit4]   2> 1722900 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1722970 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1722990 INFO  (jetty-launcher-2526-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42857/solr
   [junit4]   2> 1723031 INFO  (jetty-launcher-2526-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42857/solr
   [junit4]   2> 1723100 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 0x15ef5d5fb4d0003, 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> 1723152 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 0x15ef5d5fb4d0004, 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> 1724323 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1724324 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:39418_solr
   [junit4]   2> 1724378 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.Overseer Overseer 
(id=98786541241696261-127.0.0.1:39418_solr-n_0000000000) starting
   [junit4]   2> 1724572 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39418_solr
   [junit4]   2> 1724730 INFO  
(OverseerStateUpdate-98786541241696261-127.0.0.1:39418_solr-n_0000000000) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1724828 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1724829 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1724831 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38368_solr
   [junit4]   2> 1724860 INFO  
(zkCallback-2537-thread-2-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1724998 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1725769 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39418.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1725820 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39418.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1725820 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39418.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1725822 INFO  (jetty-launcher-2526-thread-1) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node1/.
   [junit4]   2> 1726006 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38368.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1726044 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38368.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1726044 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38368.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1726045 INFO  (jetty-launcher-2526-thread-2) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node2/.
   [junit4]   2> 1726160 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1726161 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42857/solr ready
   [junit4]   2> 1726162 INFO  
(SUITE-TestTlogReplica-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.TestTlogReplica Using legacyCloud?: false
   [junit4]   2> 1726317 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params 
val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1726339 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} 
status=0 QTime=22
   [junit4]   2> 1726380 INFO  
(TEST-TestTlogReplica.testOnlyLeaderIndexes-seed#[E5C5B346BFC8A7D6]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnlyLeaderIndexes
   [junit4]   2> 1726381 INFO  (qtp394566547-7701) [n:127.0.0.1:39418_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_only_leader_indexes&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1726425 INFO  
(OverseerThreadFactory-2128-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
tlog_replica_test_only_leader_indexes
   [junit4]   2> 1726718 INFO  
(OverseerStateUpdate-98786541241696261-127.0.0.1:39418_solr-n_0000000000) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"tlog_replica_test_only_leader_indexes",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   
"core":"tlog_replica_test_only_leader_indexes_shard1_replica_t1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:39418/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 1726720 INFO  
(OverseerStateUpdate-98786541241696261-127.0.0.1:39418_solr-n_0000000000) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"tlog_replica_test_only_leader_indexes",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   
"core":"tlog_replica_test_only_leader_indexes_shard1_replica_t2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:38368/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 1727064 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_only_leader_indexes_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_only_leader_indexes&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 1727064 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1727218 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1727218 INFO  
(zkCallback-2537-thread-2-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1727518 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_only_leader_indexes_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_only_leader_indexes&shard=shard1&wt=javabin&version=2&replicaType=TLOG
   [junit4]   2> 1727519 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1727713 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1727713 INFO  
(zkCallback-2537-thread-2-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1727729 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1727766 INFO  
(zkCallback-2538-thread-2-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1728242 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1728278 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.s.IndexSchema 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1] Schema name=minimal
   [junit4]   2> 1728280 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1728280 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.CoreContainer Creating SolrCore 
'tlog_replica_test_only_leader_indexes_shard1_replica_t1' using configuration 
from collection tlog_replica_test_only_leader_indexes, trusted=true
   [junit4]   2> 1728281 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39418.solr.core.tlog_replica_test_only_leader_indexes.shard1.replica_t1' 
(registry 'solr.core.tlog_replica_test_only_leader_indexes.shard1.replica_t1') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1728281 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1728281 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
[[tlog_replica_test_only_leader_indexes_shard1_replica_t1] ] Opening new 
SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node1/tlog_replica_test_only_leader_indexes_shard1_replica_t1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node1/./tlog_replica_test_only_leader_indexes_shard1_replica_t1/data/]
   [junit4]   2> 1728602 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1728602 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1728621 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1728621 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1728622 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@467af2d0[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main]
   [junit4]   2> 1728709 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1728710 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1728710 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1728710 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1580584667506016256
   [junit4]   2> 1728765 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_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> 1728766 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1728914 INFO  
(searcherExecutor-2133-thread-1-processing-n:127.0.0.1:39418_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node3) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1] Registered new 
searcher 
Searcher@467af2d0[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1728914 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1728915 INFO  
(zkCallback-2537-thread-2-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1728916 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1728928 INFO  
(zkCallback-2538-thread-2-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1728952 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.s.IndexSchema 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2] Schema name=minimal
   [junit4]   2> 1728986 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1728986 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.CoreContainer Creating SolrCore 
'tlog_replica_test_only_leader_indexes_shard1_replica_t2' using configuration 
from collection tlog_replica_test_only_leader_indexes, trusted=true
   [junit4]   2> 1728987 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38368.solr.core.tlog_replica_test_only_leader_indexes.shard1.replica_t2' 
(registry 'solr.core.tlog_replica_test_only_leader_indexes.shard1.replica_t2') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b879c24
   [junit4]   2> 1728987 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1728987 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
[[tlog_replica_test_only_leader_indexes_shard1_replica_t2] ] Opening new 
SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node2/tlog_replica_test_only_leader_indexes_shard1_replica_t2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestTlogReplica_E5C5B346BFC8A7D6-001/tempDir-001/node2/./tlog_replica_test_only_leader_indexes_shard1_replica_t2/data/]
   [junit4]   2> 1729348 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1729348 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1729349 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1729349 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1729350 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@f11ec33[tlog_replica_test_only_leader_indexes_shard1_replica_t2] main]
   [junit4]   2> 1729351 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1729351 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1729352 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1729352 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1580584668179202048
   [junit4]   2> 1729367 INFO  
(searcherExecutor-2138-thread-1-processing-n:127.0.0.1:38368_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node4) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2] Registered new 
searcher 
Searcher@f11ec33[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1729787 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1729787 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1729787 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SyncStrategy 
Sync replicas to 
https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/
   [junit4]   2> 1729787 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.u.PeerSync 
PeerSync: core=tlog_replica_test_only_leader_indexes_shard1_replica_t1 
url=https://127.0.0.1:39418/solr START 
replicas=[https://127.0.0.1:38368/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/]
 nUpdates=100
   [junit4]   2> 1729825 INFO  (qtp1877595331-7691) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_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> 1730115 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.u.PeerSync 
PeerSync: core=tlog_replica_test_only_leader_indexes_shard1_replica_t1 
url=https://127.0.0.1:39418/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1730115 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SyncStrategy 
Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1730115 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_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> 1730115 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1730115 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.ZkController 
tlog_replica_test_only_leader_indexes_shard1_replica_t1 stopping background 
replication from leader
   [junit4]   2> 1730117 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/
 shard1
   [junit4]   2> 1730232 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730243 INFO  
(zkCallback-2537-thread-2-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730233 INFO  
(zkCallback-2538-thread-2-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730233 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730298 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 1730299 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=tlog_replica_test_only_leader_indexes_shard1_replica_t1&action=CREATE&numShards=1&collection=tlog_replica_test_only_leader_indexes&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=3234
   [junit4]   2> 1730375 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.ZkController 
tlog_replica_test_only_leader_indexes_shard1_replica_t2 starting background 
replication from leader
   [junit4]   2> 1730375 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll 
interval: 00:00:03
   [junit4]   2> 1730395 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 1730395 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1730396 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=tlog_replica_test_only_leader_indexes_shard1_replica_t2&action=CREATE&numShards=1&collection=tlog_replica_test_only_leader_indexes&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
 status=0 QTime=2877
   [junit4]   2> 1730445 INFO  
(zkCallback-2538-thread-2-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730445 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730445 INFO  
(zkCallback-2537-thread-3-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730445 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730498 INFO  (qtp394566547-7701) [n:127.0.0.1:39418_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> 1730521 ERROR 
(OverseerThreadFactory-2128-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.OverseerTaskProcessor 
:org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode 
for /overseer/collection-queue-work/qnr-0000000000
   [junit4]   2>        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:111)
   [junit4]   2>        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2>        at 
org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1327)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:374)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:371)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:371)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:572)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskQueue.remove(OverseerTaskQueue.java:94)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.markTaskComplete(OverseerTaskProcessor.java:526)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:484)
   [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> 1730522 WARN  
(OverseerThreadFactory-2128-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.OverseerTaskProcessor Resetting task: 
/overseer/collection-queue-work/qn-0000000000, requestid: null, taskKey: 
tlog_replica_test_only_leader_indexes
   [junit4]   2> 1730558 INFO  
(zkCallback-2538-thread-2-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730558 INFO  
(zkCallback-2538-thread-1-processing-n:127.0.0.1:38368_solr) 
[n:127.0.0.1:38368_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730559 INFO  
(zkCallback-2537-thread-3-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1730559 INFO  
(zkCallback-2537-thread-1-processing-n:127.0.0.1:39418_solr) 
[n:127.0.0.1:39418_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/tlog_replica_test_only_leader_indexes/state.json] for 
collection [tlog_replica_test_only_leader_indexes] has occurred - updating... 
(live nodes size: [2])
   [junit4]   2> 1731508 INFO  (qtp394566547-7701) [n:127.0.0.1:39418_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={pullReplicas=0&replicationFactor=0&collection.configName=conf&maxShardsPerNode=100&name=tlog_replica_test_only_leader_indexes&nrtReplicas=0&action=CREATE&numShards=1&tlogReplicas=2&wt=javabin&version=2}
 status=0 QTime=5126
   [junit4]   2> 1731615 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/
   [junit4]   2> 1731687 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1731688 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1731688 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1731688 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1731688 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 1731688 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher New index in Master. Deleting mine...
   [junit4]   2> 1731726 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@67124f4c[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main]
   [junit4]   2> 1731728 INFO  
(searcherExecutor-2138-thread-1-processing-n:127.0.0.1:38368_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node4) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2] Registered new 
searcher 
Searcher@67124f4c[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1731781 INFO  (qtp1877595331-7691) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[1
 (1580584670672715776), 2 (1580584670703124480), 3 (1580584670703124481), 4 
(1580584670703124482)]} 0 0
   [junit4]   2> 1731800 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[1 (1580584670672715776), 2 
(1580584670703124480), 3 (1580584670703124481), 4 (1580584670703124482)]} 0 69
   [junit4]   2> 1731855 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1731856 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1731895 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1731962 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 
QTime=48
   [junit4]   2> 1732119 INFO  (qtp1877595331-7691) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732263 INFO  (qtp1877595331-7697) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732264 INFO  (qtp1877595331-7690) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732283 INFO  (qtp1877595331-7690) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732466 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{delete=[1
 (-1580584671255724032)]} 0 0
   [junit4]   2> 1732466 INFO  (qtp394566547-7706) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{delete=[1 (-1580584671255724032)]} 0 
180
   [junit4]   2> 1732483 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@375fdd94[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
realtime]
   [junit4]   2> 1732483 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4aedb969[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
realtime]
   [junit4]   2> 1732484 INFO  (qtp1877595331-7697) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1580584671445516288&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{deleteByQuery=id:2
 (-1580584671445516288)} 0 0
   [junit4]   2> 1732485 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{deleteByQuery=id:2 
(-1580584671445516288)} 0 17
   [junit4]   2> 1732521 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732521 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 
QTime=0
   [junit4]   2> 1732538 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/get params={distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 
QTime=16
   [junit4]   2> 1732702 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1580584671691931648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1732702 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7cbe76f3 
commitCommandVersion:1580584671691931648
   [junit4]   2> 1732702 INFO  (qtp1877595331-7690) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 1732704 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1f96a2d9[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main]
   [junit4]   2> 1732705 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1732714 INFO  
(searcherExecutor-2133-thread-1-processing-n:127.0.0.1:39418_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node3) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1] Registered new 
searcher 
Searcher@1f96a2d9[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C4/2:delGen=2)))}
   [junit4]   2> 1732749 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_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:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 47
   [junit4]   2> 1732750 INFO  (qtp394566547-7701) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update 
params={_stateVer_=tlog_replica_test_only_leader_indexes:7&commit=true&wt=javabin&version=2}{commit=}
 0 195
   [junit4]   2> 1732864 INFO  (qtp394566547-7702) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
   [junit4]   2> 1733067 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733176 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733285 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733395 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733504 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733614 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733719 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733829 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1733930 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734040 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734149 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734259 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734384 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734494 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734603 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734624 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1734625 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1734625 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Master's version: 1507363006298
   [junit4]   2> 1734625 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1734625 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 1734625 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1734625 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=0
   [junit4]   2> 1734626 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 13
   [junit4]   2> 1734626 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@624bb8f4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31a094b7)
   [junit4]   2> 1734627 INFO  (qtp394566547-7706) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_2.liv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734699 INFO  (qtp394566547-7702) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734786 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_Direct_0.dvdd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734787 INFO  (qtp394566547-7701) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734788 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734808 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734809 INFO  (qtp394566547-7706) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_Memory_0.ram&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734822 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1734823 INFO  (qtp394566547-7702) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734825 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734845 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_Direct_0.dvdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734846 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734859 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734860 INFO  (qtp394566547-7707) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1734861 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2005) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@624bb8f4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31a094b7)
   [junit4]   2> 1734879 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1734879 INFO  (indexFetcher-2144-thread-1) 
[n:127.0.0.1:38368_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node4 x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@207d120[tlog_replica_test_only_leader_indexes_shard1_replica_t2] main]
   [junit4]   2> 1734880 INFO  
(searcherExecutor-2138-thread-1-processing-n:127.0.0.1:38368_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node4) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2] Registered new 
searcher 
Searcher@207d120[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C4/2:delGen=2)))}
   [junit4]   2> 1734932 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.S.Request 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
   [junit4]   2> 1734954 INFO  (qtp1877595331-7695) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[15
 (1580584674051227648)]} 0 0
   [junit4]   2> 1734954 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[15 (1580584674051227648)]} 0 2
   [junit4]   2> 1734989 INFO  (qtp1877595331-7690) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[16
 (1580584674054373376)]} 0 0
   [junit4]   2> 1734989 INFO  (qtp394566547-7708) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[16 (1580584674054373376)]} 0 34
   [junit4]   2> 1734990 INFO  (qtp1877595331-7697) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[17
 (1580584674091073536)]} 0 0
   [junit4]   2> 1734991 INFO  (qtp394566547-7705) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[17 (1580584674091073536)]} 0 0
   [junit4]   2> 1734992 INFO  (qtp1877595331-7693) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[18
 (1580584674092122112)]} 0 0
   [junit4]   2> 1735005 INFO  (qtp394566547-7706) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[18 (1580584674092122112)]} 0 13
   [junit4]   2> 1735006 INFO  (qtp1877595331-7694) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&wt=javabin&version=2}{add=[19
 (1580584674106802176)]} 0 0
   [junit4]   2> 1735006 INFO  (qtp394566547-7702) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t1]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[19 (1580584674106802176)]} 0 1
   [junit4]   2> 1735062 INFO  (qtp1877595331-7690) [n:127.0.0.1:38368_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node4 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.p.LogUpdateProcessorFactory 
[tlog_replica_test_only_leader_indexes_shard1_replica_t2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:39418/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 1735098 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1580584674204319744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1735134 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7cbe76f3 
commitCommandVersion:1580584674204319744
   [junit4]   2> 1735187 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@e3f4788[tlog_replica_test_only_leader_indexes_shard1_replica_t1] main]
   [junit4]   2> 1735188 INFO  (qtp394566547-7704) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1735189 INFO  
(searcherExecutor-2133-thread-1-processing-n:127.0.0.1:39418_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node3) [n:127.0.0.1:39418_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node3 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
[tlog_replica_test_only_leader_indexes_shard1_replica

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

llection1] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4489229 INFO  
(zkCallback-3842-thread-2-processing-n:127.0.0.1:43223_solr) 
[n:127.0.0.1:43223_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCollection2/state.json] for collection [testCollection2] 
has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4489230 INFO  
(zkCallback-3842-thread-3-processing-n:127.0.0.1:43223_solr) 
[n:127.0.0.1:43223_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testCollection2/state.json] for collection [testCollection2] 
has occurred - updating... (live nodes size: [1])
   [junit4]   2> 4489246 INFO  (coreCloseExecutor-6836-thread-3) 
[n:127.0.0.1:43223_solr c:testCollection2 s:shard2 r:core_node4 
x:testCollection2_shard2_replica_n3] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.testCollection2.shard2.leader, 
tag=1395637865
   [junit4]   2> 4489246 INFO  (coreCloseExecutor-6836-thread-2) 
[n:127.0.0.1:43223_solr c:testCollection2 s:shard1 r:core_node2 
x:testCollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.core.testCollection2.shard1.replica_n1, 
tag=277567795
   [junit4]   2> 4489246 INFO  (coreCloseExecutor-6836-thread-2) 
[n:127.0.0.1:43223_solr c:testCollection2 s:shard1 r:core_node2 
x:testCollection2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6f85a990: rootName = 
solr_43223, domain = solr.core.testCollection2.shard1.replica_n1, service url = 
null, agent id = null] for registry solr.core.testCollection2.shard1.replica_n1 
/ com.codahale.metrics.MetricRegistry@c38d295
   [junit4]   2> 4489256 INFO  (coreCloseExecutor-6836-thread-2) 
[n:127.0.0.1:43223_solr c:testCollection2 s:shard1 r:core_node2 
x:testCollection2_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.testCollection2.shard1.leader, 
tag=277567795
   [junit4]   2> 4489256 INFO  (coreCloseExecutor-6836-thread-1) 
[n:127.0.0.1:43223_solr c:testCollection1 s:shard1 r:core_node3 
x:testCollection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.testCollection1.shard1.leader, 
tag=2072416865
   [junit4]   2> 4489278 INFO  (jetty-closer-3836-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=98786722175844355-127.0.0.1:43223_solr-n_0000000000) closing
   [junit4]   2> 4489278 INFO  
(OverseerStateUpdate-98786722175844355-127.0.0.1:43223_solr-n_0000000000) 
[n:127.0.0.1:43223_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:43223_solr
   [junit4]   2> 4490783 WARN  
(zkCallback-3842-thread-3-processing-n:127.0.0.1:43223_solr) 
[n:127.0.0.1:43223_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 4490783 INFO  (jetty-closer-3836-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@338522e3{/solr,null,UNAVAILABLE}
   [junit4]   2> 4490792 ERROR 
(SUITE-LeaderElectionContextKeyTest-seed#[E5C5B346BFC8A7D6]-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> 4490793 INFO  
(SUITE-LeaderElectionContextKeyTest-seed#[E5C5B346BFC8A7D6]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36860 36860
   [junit4]   2> 4491086 INFO  (Thread-3910) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:36860 36860
   [junit4]   2> 4491087 WARN  (Thread-3910) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        2       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/collections/testCollection2/state.json
   [junit4]   2>        6       /solr/collections/testCollection1/state.json
   [junit4]   2>        2       /solr/clusterstate.json
   [junit4]   2>        2       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        2       /solr/live_nodes
   [junit4]   2>        2       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderElectionContextKeyTest_E5C5B346BFC8A7D6-001
   [junit4]   2> Oct 07, 2017 8:42:45 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=RandomSimilarity(queryNorm=false): {}, locale=is-IS, timezone=Asia/Dhaka
   [junit4]   2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 
1.8.0_144 (64-bit)/cpus=4,threads=1,free=206467536,total=526909440
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedIntervalFacetingTest, JsonLoaderTest, OverseerTaskQueueTest, 
PrimUtilsTest, SpellCheckComponentTest, SampleTest, DocValuesMultiTest, 
SolrCmdDistributorTest, TestFilteredDocIdSet, TestNumericRangeQuery64, 
CollectionsAPIAsyncDistributedZkTest, TestSimpleTrackingShardHandler, 
TestOverriddenPrefixQueryForCustomFieldType, SaslZkACLProviderTest, 
DirectoryFactoryTest, MetricsHandlerTest, AnalyticsQueryTest, TestCharFilters, 
SolrCloudExampleTest, TestSolrConfigHandlerCloud, TestPKIAuthenticationPlugin, 
TestXIncludeConfig, TestGeoJSONResponseWriter, SOLR749Test, 
TestManagedSynonymFilterFactory, AddSchemaFieldsUpdateProcessorFactoryTest, 
ClassificationUpdateProcessorFactoryTest, TestObjectReleaseTracker, 
SpatialHeatmapFacetsTest, TestSubQueryTransformer, NoCacheHeaderTest, 
TestIntervalFaceting, TestFieldCacheWithThreads, DeleteStatusTest, TestUpdate, 
MoveReplicaTest, TestConfigSetImmutable, TestCloudDeleteByQuery, 
ManagedSchemaRoundRobinCloudTest, TestCollectionAPIs, 
TestSolrCloudWithSecureImpersonation, RestartWhileUpdatingTest, 
UpdateRequestProcessorFactoryTest, TestSweetSpotSimilarityFactory, TestSolrJ, 
TestHdfsBackupRestoreCore, TestSolr4Spatial2, 
VMParamsZkACLAndCredentialsProvidersTest, ConvertedLegacyTest, 
CloneFieldUpdateProcessorFactoryTest, TestExceedMaxTermLength, DocValuesTest, 
ShardRoutingTest, TestFiltering, TestEmbeddedSolrServerAdminHandler, 
DistributedTermsComponentTest, TestLRUCache, TestSerializedLuceneMatchVersion, 
TestElisionMultitermQuery, TestPartialUpdateDeduplication, 
TestDistributedStatsComponentCardinality, 
StatelessScriptUpdateProcessorFactoryTest, SoftAutoCommitTest, 
TestStressVersions, TestHighFrequencyDictionaryFactory, 
TestAddFieldRealTimeGet, TestBM25SimilarityFactory, TestNumericTerms64, 
FileUtilsTest, TestCoreDiscovery, DistributedMLTComponentTest, 
TestMiniSolrCloudClusterSSL, ResourceLoaderTest, 
SegmentsInfoRequestHandlerTest, DisMaxRequestHandlerTest, 
DistributedQueryComponentOptimizationTest, TestNRTOpen, SolrMetricManagerTest, 
TestRandomRequestDistribution, TestValueSourceCache, TestSchemaResource, 
TestQueryTypes, TestPushWriter, TestNonDefinedSimilarityFactory, 
SharedFSAutoReplicaFailoverUtilsTest, SpatialRPTFieldTypeTest, 
DistributedFacetPivotLargeTest, StressHdfsTest, ZkControllerTest, 
SearchHandlerTest, ChaosMonkeySafeLeaderTest, ParsingFieldUpdateProcessorsTest, 
TestCloudSearcherWarming, FileBasedSpellCheckerTest, 
MissingSegmentRecoveryTest, TestInPlaceUpdatesStandalone, 
OpenCloseCoreStressTest, DistributedFacetPivotSmallTest, 
UUIDUpdateProcessorFallbackTest, TestPivotHelperCode, 
TestRetrieveFieldsOptimizer, TestDelegationWithHadoopAuth, TestLegacyTerms, 
TestSimpleTextCodec, RemoteQueryErrorTest, BasicFunctionalityTest, 
SliceStateTest, TestFieldCacheSort, CSVRequestHandlerTest, TestRTGBase, 
TestPullReplica, DateRangeFieldTest, AnalysisAfterCoreReloadTest, 
TestHashPartitioner, DistanceFunctionTest, 
TestTolerantUpdateProcessorRandomCloud, TestComplexPhraseQParserPlugin, 
TestCloudSchemaless, OutOfBoxZkACLAndCredentialsProvidersTest, 
TestAtomicUpdateErrorCases, TestFuzzyAnalyzedSuggestions, TestRealTimeGet, 
TestStressReorder, TestReload, TestCoreContainer, TestGroupingSearch, TestSort, 
DirectUpdateHandlerTest, HighlighterTest, SignatureUpdateProcessorFactoryTest, 
SolrRequestParserTest, TestFoldingMultitermQuery, SuggesterTest, 
TestStressLucene, WordBreakSolrSpellCheckerTest, 
SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, 
QueryEqualityTest, DebugComponentTest, IndexBasedSpellCheckerTest, 
TestQueryUtils, DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, 
TermsComponentTest, TestSolrQueryParser, LoggingHandlerTest, IndexSchemaTest, 
TestCollationField, TestCSVResponseWriter, TestPHPSerializedResponseWriter, 
TestComponentsName, HighlighterConfigTest, AlternateDirectoryTest, 
TestSolrIndexConfig, ResponseLogComponentTest, BadComponentTest, 
TestStressRecovery, TestSolrDeletionPolicy2, MultiTermTest, TestSearchPerf, 
MinimalSchemaTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, 
TestPhraseSuggestions, TestCodecSupport, OpenExchangeRatesOrgProviderTest, 
PluginInfoTest, TestFastLRUCache, DateMathParserTest, TestSystemIdResolver, 
TestSuggestSpellingConverter, DOMUtilTest, TestUtils, UUIDFieldTest, 
TestHighlightDedupGrouping, TestEmbeddedSolrServerSchemaAPI, 
TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, 
AliasIntegrationTest, AsyncCallRequestStatusResponseTest, CdcrBootstrapTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionTooManyReplicasTest, 
DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, 
DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, 
DistributedVersionInfoTest, LeaderElectionContextKeyTest]
   [junit4] Completed [503/734 (2!)] on J1 in 10.60s, 1 test, 1 failure <<< 
FAILURES!

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

Reply via email to