Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2033/

2 tests failed.
FAILED:  org.apache.solr.cloud.TestTlogReplica.testKillTlogReplica

Error Message:
Error from server at 
http://127.0.0.1:35354/solr/tlog_replica_test_kill_tlog_replica_shard1_replica_t2:
 Async exception during distributed update: Broken pipe (Write failed)

Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from 
server at 
http://127.0.0.1:35354/solr/tlog_replica_test_kill_tlog_replica_shard1_replica_t2:
 Async exception during distributed update: Broken pipe (Write failed)
        at 
__randomizedtesting.SeedInfo.seed([2BFD0890D50F4386:F4CFCC5524ACCC8B]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:922)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
        at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
        at 
org.apache.solr.cloud.TestTlogReplica.testKillTlogReplica(TestTlogReplica.java:446)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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)
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at 
http://127.0.0.1:35354/solr/tlog_replica_test_kill_tlog_replica_shard1_replica_t2:
 Async exception during distributed update: Broken pipe (Write failed)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:626)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:252)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:516)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        ... 1 more


FAILED:  org.apache.solr.update.AutoCommitTest.testMaxTime

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([2BFD0890D50F4386:B10975724B95DFBA]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879)
        at 
org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:270)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=id:529&qt=&start=0&rows=20&version=2.2
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:872)
        ... 40 more




Build Log:
[...truncated 11391 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestTlogReplica
   [junit4]   2> 1028515 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/init-core-data-001
   [junit4]   2> 1028515 WARN  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=26 numCloses=26
   [junit4]   2> 1028515 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1028549 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1028550 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001
   [junit4]   2> 1028550 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1028573 INFO  (Thread-950) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1028573 INFO  (Thread-950) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1028685 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46693
   [junit4]   2> 1028686 ERROR (Thread-950) [    ] 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> 1028841 INFO  (jetty-launcher-995-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1028843 INFO  (jetty-launcher-995-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1028889 INFO  (jetty-launcher-995-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2a7b4b03{/solr,null,AVAILABLE}
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@67d7a204{HTTP/1.1,[http/1.1]}{127.0.0.1:35354}
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.e.j.s.Server Started @1039391ms
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35354}
   [junit4]   2> 1028891 ERROR (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-23T21:34:02.166Z
   [junit4]   2> 1028891 INFO  (jetty-launcher-995-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2783c1c2{/solr,null,AVAILABLE}
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6dfc97a4{HTTP/1.1,[http/1.1]}{127.0.0.1:42424}
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.e.j.s.Server Started @1039392ms
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=42424}
   [junit4]   2> 1028892 ERROR (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1028892 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-23T21:34:02.167Z
   [junit4]   2> 1028973 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1029008 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1029008 INFO  (jetty-launcher-995-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
   [junit4]   2> 1029089 INFO  (jetty-launcher-995-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46693/solr
   [junit4]   2> 1029173 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1029174 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:42424_solr
   [junit4]   2> 1029174 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.Overseer Overseer 
(id=98359419991490566-127.0.0.1:42424_solr-n_0000000000) starting
   [junit4]   2> 1029193 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:42424_solr
   [junit4]   2> 1029213 INFO  
(OverseerStateUpdate-98359419991490566-127.0.0.1:42424_solr-n_0000000000) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1029306 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1029307 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1029308 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35354_solr
   [junit4]   2> 1029350 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1029355 INFO  
(zkCallback-1008-thread-2-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1029495 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42424.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029535 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42424.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029535 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42424.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029536 INFO  (jetty-launcher-995-thread-2) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node2/.
   [junit4]   2> 1029609 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35354.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029629 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35354.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029629 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35354.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@3d4ac6fc
   [junit4]   2> 1029639 INFO  (jetty-launcher-995-thread-1) 
[n:127.0.0.1:35354_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node1/.
   [junit4]   2> 1029828 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1029828 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46693/solr ready
   [junit4]   2> 1029845 INFO  
(SUITE-TestTlogReplica-seed#[2BFD0890D50F4386]-worker) [    ] 
o.a.s.c.TestTlogReplica Using legacyCloud?: false
   [junit4]   2> 1029903 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_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> 1029920 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_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=16
   [junit4]   2> 1030072 INFO  
(TEST-TestTlogReplica.testOnlyLeaderIndexes-seed#[2BFD0890D50F4386]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOnlyLeaderIndexes
   [junit4]   2> 1030073 INFO  (qtp393002760-5302) [n:127.0.0.1:35354_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> 1030138 INFO  
(OverseerThreadFactory-2103-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
tlog_replica_test_only_leader_indexes
   [junit4]   2> 1030411 INFO  
(OverseerStateUpdate-98359419991490566-127.0.0.1:42424_solr-n_0000000000) 
[n:127.0.0.1:42424_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":"http://127.0.0.1:35354/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 1030413 INFO  
(OverseerStateUpdate-98359419991490566-127.0.0.1:42424_solr-n_0000000000) 
[n:127.0.0.1:42424_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":"http://127.0.0.1:42424/solr";,
   [junit4]   2>   "type":"TLOG"} 
   [junit4]   2> 1030614 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&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> 1030615 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1030618 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node1&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> 1030618 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1030793 INFO  
(zkCallback-1008-thread-2-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1030793 INFO  
(zkCallback-1008-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1030793 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1030794 INFO  
(zkCallback-1003-thread-2-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1031628 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1031650 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031652 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031652 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031652 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_42424.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@3d4ac6fc
   [junit4]   2> 1031652 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1031653 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node2/tlog_replica_test_only_leader_indexes_shard1_replica_t2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node2/./tlog_replica_test_only_leader_indexes_shard1_replica_t2/data/]
   [junit4]   2> 1031712 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1031756 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1031758 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1031758 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1031759 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35354.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@3d4ac6fc
   [junit4]   2> 1031759 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1031759 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node1/tlog_replica_test_only_leader_indexes_shard1_replica_t1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestTlogReplica_2BFD0890D50F4386-001/tempDir-001/node1/./tlog_replica_test_only_leader_indexes_shard1_replica_t1/data/]
   [junit4]   2> 1031849 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031849 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031850 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1031850 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1031967 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4b6c0cad[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main]
   [junit4]   2> 1031969 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1031970 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1031979 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1031980 INFO  
(searcherExecutor-2108-thread-1-processing-n:127.0.0.1:42424_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node2) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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@4b6c0cad[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1031982 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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 
1573750723321004032
   [junit4]   2> 1031996 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1032038 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1032038 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1032039 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1032039 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1032052 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@ae3d360[tlog_replica_test_only_leader_indexes_shard1_replica_t1] main]
   [junit4]   2> 1032072 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1032072 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1032073 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1032074 INFO  
(searcherExecutor-2109-thread-1-processing-n:127.0.0.1:35354_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node1) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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@ae3d360[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1032074 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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 
1573750723417473024
   [junit4]   2> 1032141 INFO  
(zkCallback-1008-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032141 INFO  
(zkCallback-1003-thread-2-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1032142 INFO  
(zkCallback-1008-thread-2-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032141 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1032496 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1032496 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1032496 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SyncStrategy 
Sync replicas to 
http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/
   [junit4]   2> 1032497 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.u.PeerSync 
PeerSync: core=tlog_replica_test_only_leader_indexes_shard1_replica_t2 
url=http://127.0.0.1:42424/solr START 
replicas=[http://127.0.0.1:35354/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t1/]
 nUpdates=100
   [junit4]   2> 1032497 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.u.PeerSync 
PeerSync: core=tlog_replica_test_only_leader_indexes_shard1_replica_t2 
url=http://127.0.0.1:42424/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1032499 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1032500 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.SyncStrategy 
Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1032500 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
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> 1032500 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1032515 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.ZkController 
tlog_replica_test_only_leader_indexes_shard1_replica_t2 stopping background 
replication from leader
   [junit4]   2> 1032528 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/
 shard1
   [junit4]   2> 1032633 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1032633 INFO  
(zkCallback-1008-thread-2-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032633 INFO  
(zkCallback-1008-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032649 INFO  
(zkCallback-1003-thread-2-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1032685 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 1032726 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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_node2&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=2112
   [junit4]   2> 1032894 INFO  
(zkCallback-1003-thread-2-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1032894 INFO  
(zkCallback-1008-thread-3-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032894 INFO  
(zkCallback-1008-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1032894 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1033088 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] o.a.s.c.ZkController 
tlog_replica_test_only_leader_indexes_shard1_replica_t1 starting background 
replication from leader
   [junit4]   2> 1033088 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll 
interval: 00:00:03
   [junit4]   2> 1033089 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 1033089 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Updated masterUrl to 
http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/
   [junit4]   2> 1033090 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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_node1&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=2472
   [junit4]   2> 1033103 INFO  (qtp909013000-5311) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1033103 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1033103 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1033103 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1033118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 1033119 INFO  (qtp393002760-5302) [n:127.0.0.1:35354_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> 1033235 INFO  
(zkCallback-1008-thread-3-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1033235 INFO  
(zkCallback-1008-thread-1-processing-n:127.0.0.1:42424_solr) 
[n:127.0.0.1:42424_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> 1033237 INFO  
(zkCallback-1003-thread-1-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1033237 INFO  
(zkCallback-1003-thread-2-processing-n:127.0.0.1:35354_solr) 
[n:127.0.0.1:35354_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> 1034119 INFO  (qtp393002760-5302) [n:127.0.0.1:35354_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=4046
   [junit4]   2> 1034153 INFO  (qtp393002760-5298) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[1
 (1573750725580685312), 2 (1573750725583831040), 3 (1573750725583831041), 4 
(1573750725583831042)]} 0 11
   [junit4]   2> 1034153 INFO  (qtp909013000-5312) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[1 (1573750725580685312), 2 
(1573750725583831040), 3 (1573750725583831041), 4 (1573750725583831042)]} 0 15
   [junit4]   2> 1034193 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=38
   [junit4]   2> 1034194 INFO  (qtp909013000-5314) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1034195 INFO  (qtp909013000-5314) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1034195 INFO  (qtp909013000-5314) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1034209 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1034210 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1034210 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1034210 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0
   [junit4]   2> 1034233 INFO  
(OverseerCollectionConfigSetProcessor-98359419991490566-127.0.0.1:42424_solr-n_0000000000)
 [n:127.0.0.1:42424_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1034252 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{delete=[1
 (-1573750725699174400)]} 0 0
   [junit4]   2> 1034253 INFO  (qtp909013000-5314) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{delete=[1 (-1573750725699174400)]} 0 
2
   [junit4]   2> 1034349 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@dd2a288[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
realtime]
   [junit4]   2> 1034360 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@318fff21[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
realtime]
   [junit4]   2> 1034361 INFO  (qtp393002760-5304) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&_version_=-1573750725702320128&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{deleteByQuery=id:2
 (-1573750725702320128)} 0 0
   [junit4]   2> 1034361 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{deleteByQuery=id:2 
(-1573750725702320128)} 0 108
   [junit4]   2> 1034412 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=28
   [junit4]   2> 1034413 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1034413 INFO  (qtp393002760-5299) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0
   [junit4]   2> 1034417 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 1034418 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573750725875335168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1034419 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5688a280 
commitCommandVersion:1573750725875335168
   [junit4]   2> 1034445 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@77eb466e[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main]
   [junit4]   2> 1034446 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1034446 INFO  
(searcherExecutor-2108-thread-1-processing-n:127.0.0.1:42424_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node2) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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@77eb466e[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C4/2:delGen=2)))}
   [junit4]   2> 1034448 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 29
   [junit4]   2> 1034497 INFO  (qtp909013000-5311) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={_stateVer_=tlog_replica_test_only_leader_indexes:6&commit=true&wt=javabin&version=2}{commit=}
 0 83
   [junit4]   2> 1034523 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1034632 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1034742 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1034843 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1034952 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035062 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035172 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035273 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035394 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035501 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035602 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035703 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035822 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1035923 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1036033 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1036098 INFO  (qtp909013000-5312) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1036118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1036118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Master's version: 1500845647694
   [junit4]   2> 1036118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1036118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 1036118 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1036119 INFO  (qtp909013000-5312) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=0
   [junit4]   2> 1036119 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 16
   [junit4]   2> 1036127 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@70a481ed 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18145955)
   [junit4]   2> 1036128 INFO  (qtp909013000-5312) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.pos&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036128 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_2.liv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036139 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036140 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_Direct_0.dvdd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036154 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=0 status=0 QTime=0
   [junit4]   2> 1036159 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036160 INFO  (qtp909013000-5311) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036161 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036162 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036162 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036186 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036187 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036198 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036198 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036199 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0_Direct_0.dvdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036200 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036220 INFO  (qtp909013000-5307) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1036220 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2494) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@70a481ed 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18145955)
   [junit4]   2> 1036222 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1036222 INFO  (indexFetcher-2119-thread-1) 
[n:127.0.0.1:35354_solr c:tlog_replica_test_only_leader_indexes s:shard1 
r:core_node1 x:tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73085485[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main]
   [junit4]   2> 1036223 INFO  
(searcherExecutor-2109-thread-1-processing-n:127.0.0.1:35354_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t1 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node1) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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@73085485[tlog_replica_test_only_leader_indexes_shard1_replica_t1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C4/2:delGen=2)))}
   [junit4]   2> 1036265 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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> 1036268 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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> 1036287 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[15
 (1573750727824637952)]} 0 0
   [junit4]   2> 1036287 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[15 (1573750727824637952)]} 0 10
   [junit4]   2> 1036289 INFO  (qtp393002760-5301) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[16
 (1573750727836172288)]} 0 0
   [junit4]   2> 1036289 INFO  (qtp909013000-5310) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[16 (1573750727836172288)]} 0 1
   [junit4]   2> 1036290 INFO  (qtp393002760-5304) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[17
 (1573750727837220864)]} 0 0
   [junit4]   2> 1036290 INFO  (qtp909013000-5311) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[17 (1573750727837220864)]} 0 1
   [junit4]   2> 1036323 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573750727872872448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1036324 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5688a280 
commitCommandVersion:1573750727872872448
   [junit4]   2> 1036326 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 0
   [junit4]   2> 1036346 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2c08a23a[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main]
   [junit4]   2> 1036347 INFO  
(searcherExecutor-2108-thread-1-processing-n:127.0.0.1:42424_solr 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2 s:shard1 
c:tlog_replica_test_only_leader_indexes r:core_node2) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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@2c08a23a[tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C4/2:delGen=2)
 Uninverting(_1(8.0.0):C3)))}
   [junit4]   2> 1036348 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
x:tlog_replica_test_only_leader_indexes_shard1_replica_t2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1036348 INFO  (qtp909013000-5313) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 25
   [junit4]   2> 1036349 INFO  (qtp909013000-5314) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={_stateVer_=tlog_replica_test_only_leader_indexes:6&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 54
   [junit4]   2> 1036380 INFO  (qtp393002760-5305) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[18
 (1573750727905378304)]} 0 0
   [junit4]   2> 1036380 INFO  (qtp909013000-5312) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[18 (1573750727905378304)]} 0 25
   [junit4]   2> 1036382 INFO  (qtp393002760-5298) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[19
 (1573750727932641280)]} 0 0
   [junit4]   2> 1036382 INFO  (qtp909013000-5308) [n:127.0.0.1:42424_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node2 
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={wt=javabin&version=2}{add=[19 (1573750727932641280)]} 0 1
   [junit4]   2> 1036396 INFO  (qtp393002760-5303) [n:127.0.0.1:35354_solr 
c:tlog_replica_test_only_leader_indexes s:shard1 r:core_node1 
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&distrib.from=http://127.0.0.1:42424/solr/tlog_replica_test_only_leader_indexes_shard1_replica_t2/&wt=javabin&version=2}{add=[20
 (1573750727948369

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

CommitWithin-seed#[2BFD0890D50F4386]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046915751938)]} 0 6119
   [junit4]   2> 1341076 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046915751939)]} 0 6119
   [junit4]   2> 1341076 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046916800512)]} 0 6119
   [junit4]   2> 1341076 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046916800513)]} 0 6119
   [junit4]   2> 1341076 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046916800514)]} 0 6119
   [junit4]   2> 1341076 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046916800515)]} 0 6120
   [junit4]   2> 1341077 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1573751046916800516)]} 0 6120
   [junit4]   2> 1341077 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1341089 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046917849088)]} 0 6133
   [junit4]   2> 1341090 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046931480576)]} 0 6133
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046931480577)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674880)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674881)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674882)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674883)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674884)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674885)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1573751046935674886)]} 0 6137
   [junit4]   2> 1341094 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1342097 INFO  (commitScheduler-2354-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1342115 INFO  (commitScheduler-2354-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@475e075c[collection1] main]
   [junit4]   2> 1342115 INFO  (commitScheduler-2354-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1342116 INFO  
(searcherExecutor-2351-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@475e075c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):c10/9:delGen=1)))}
   [junit4]   2> 1342361 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 1342362 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 1342362 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[2BFD0890D50F4386]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 1342362 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1342362 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2074760765
   [junit4]   2> 1342363 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1342367 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1342368 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1342368 INFO  
(SUITE-AutoCommitTest-seed#[2BFD0890D50F4386]-worker) [    x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1342368 INFO  
(coreCloseExecutor-2355-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@11c0aeb5
   [junit4]   2> 1342368 INFO  
(coreCloseExecutor-2355-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1342368 INFO  
(coreCloseExecutor-2355-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@63c1217e commitCommandVersion:0
   [junit4]   2> 1342369 INFO  
(coreCloseExecutor-2355-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=297840309
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_2BFD0890D50F4386-001
   [junit4]   2> NOTE: test params are: 
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=10, maxDocsPerChunk=9, blockSize=120), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=10, blockSize=120)), sim=RandomSimilarity(queryNorm=false): {}, 
locale=ar, timezone=America/Aruba
   [junit4]   2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=166957160,total=530055168
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrConfigHandler, 
TestJettySolrRunner, BlockDirectoryTest, PrimitiveFieldTypeTest, TestLFUCache, 
ClassificationUpdateProcessorTest, SearchHandlerTest, TestInfoStreamLogging, 
TestConfigSetProperties, TestDynamicFieldCollectionResource, 
RegexBoostProcessorTest, TestDeleteCollectionOnDownNodes, 
TestConfigSetsAPIExclusivity, ReplicaListTransformerTest, LeaderElectionTest, 
VMParamsZkACLAndCredentialsProvidersTest, TestExpandComponent, 
PropertiesRequestHandlerTest, TestStressUserVersions, SolrTestCaseJ4Test, 
AtomicUpdatesTest, TestSQLHandler, MoveReplicaHDFSTest, TestReload, 
TestPrepRecovery, SimpleFacetsTest, DirectUpdateHandlerTest, CursorMarkTest, 
BJQParserTest, LeaderInitiatedRecoveryOnShardRestartTest, 
SolrGraphiteReporterTest, PeerSyncReplicationTest, TestPseudoReturnFields, 
TestSubQueryTransformer, DeleteReplicaTest, BasicDistributedZk2Test, 
TimeZoneUtilsTest, BlockJoinFacetSimpleTest, SolrMetricReporterTest, 
TestCollectionAPI, ExitableDirectoryReaderTest, TestSolrDeletionPolicy2, 
BadCopyFieldTest, DistributedQueueTest, BaseCdcrDistributedZkTest, 
TestManagedStopFilterFactory, RollingRestartTest, 
SignatureUpdateProcessorFactoryTest, CopyFieldTest, 
SuggestComponentContextFilterQueryTest, TestSchemaManager, 
TestXmlQParserPlugin, QueryEqualityTest, ClusterStateTest, TestRecovery, 
AliasIntegrationTest, TestZkChroot, TestRandomFlRTGCloud, TestBulkSchemaAPI, 
SimplePostToolTest, MetricUtilsTest, RecoveryZkTest, TestConfigsApi, 
TestSuggestSpellingConverter, TestSurroundQueryParser, TestRawTransformer, 
CleanupOldIndexTest, TestCollationFieldDocValues, AddBlockUpdateTest, 
TestFreeTextSuggestions, SmileWriterTest, TestSolrCoreSnapshots, 
DistributedSuggestComponentTest, SortByFunctionTest, TestManagedResource, 
SuggesterFSTTest, AutoCommitTest]
   [junit4] Completed [224/727 (2!)] on J2 in 11.66s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 44949 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to