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

11 tests failed.
FAILED:  org.apache.solr.cloud.BasicDistributedZkTest.test

Error Message:
distrib-dup-test-chain-explicit: doc#3 has wrong value for regex_dup_A_s 
expected:<x_x> but was:<null>

Stack Trace:
java.lang.AssertionError: distrib-dup-test-chain-explicit: doc#3 has wrong 
value for regex_dup_A_s expected:<x_x> but was:<null>
        at 
__randomizedtesting.SeedInfo.seed([460134BEE39B8E15:CE550B644D67E3ED]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.testUpdateProcessorsRunOnlyOnce(BasicDistributedZkTest.java:704)
        at 
org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkTest.java:381)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1008)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest.test

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)


FAILED:  org.apache.solr.cloud.TestPullReplica.testKillLeader

Error Message:
Replica core_node4 not up to date after 10 seconds expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: Replica core_node4 not up to date after 10 seconds 
expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([460134BEE39B8E15:F17C00A81201A43]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.solr.cloud.TestPullReplica.waitForNumDocsInAllReplicas(TestPullReplica.java:542)
        at 
org.apache.solr.cloud.TestPullReplica.doTestNoLeader(TestPullReplica.java:490)
        at 
org.apache.solr.cloud.TestPullReplica.testKillLeader(TestPullReplica.java:309)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.TestPullReplica.testRemoveAllWriterReplicas

Error Message:
Replica core_node4 not up to date after 10 seconds expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: Replica core_node4 not up to date after 10 seconds 
expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([460134BEE39B8E15:D50FA885F76567BC]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.solr.cloud.TestPullReplica.waitForNumDocsInAllReplicas(TestPullReplica.java:542)
        at 
org.apache.solr.cloud.TestPullReplica.doTestNoLeader(TestPullReplica.java:490)
        at 
org.apache.solr.cloud.TestPullReplica.testRemoveAllWriterReplicas(TestPullReplica.java:303)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.handler.component.InfixSuggestersTest.testBlendedInfixSuggesterBuildThenReload

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.component.InfixSuggestersTest

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)


FAILED:  org.apache.solr.client.solrj.embedded.TestSolrProperties.testProperties

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([38EAB06EC44A11A7]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.client.solrj.embedded.TestSolrProperties

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([38EAB06EC44A11A7]:0)


FAILED:  
org.apache.solr.cloud.autoscaling.sim.TestGenericDistributedQueue.testDistributedQueue

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestGenericDistributedQueue

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([460134BEE39B8E15]:0)




Build Log:
[...truncated 12217 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestPullReplica
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/init-core-data-001
   [junit4]   2> 29184 WARN  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 29184 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 29186 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 29186 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 29190 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001
   [junit4]   2> 29234 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 29236 INFO  (Thread-2) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 29237 INFO  (Thread-2) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 29536 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46364
   [junit4]   2> 29617 ERROR (Thread-2) [    ] 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> 29847 INFO  (zkConnectionManagerCallback-6-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 30007 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x1074e029a930000, likely client has closed socket
   [junit4]   2> 30782 INFO  (jetty-launcher-3-thread-1) [    ] o.e.j.s.Server 
jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 30796 INFO  (jetty-launcher-3-thread-2) [    ] o.e.j.s.Server 
jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 30904 INFO  (jetty-launcher-3-thread-1) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 30904 INFO  (jetty-launcher-3-thread-1) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 30909 INFO  (jetty-launcher-3-thread-1) [    ] o.e.j.s.session 
node0 Scavenging every 660000ms
   [junit4]   2> 31045 INFO  (jetty-launcher-3-thread-2) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 31045 INFO  (jetty-launcher-3-thread-2) [    ] o.e.j.s.session 
No SessionScavenger set, using defaults
   [junit4]   2> 31045 INFO  (jetty-launcher-3-thread-2) [    ] o.e.j.s.session 
node0 Scavenging every 600000ms
   [junit4]   2> 31054 INFO  (jetty-launcher-3-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@25244da4{/solr,null,AVAILABLE}
   [junit4]   2> 31067 INFO  (jetty-launcher-3-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5e5ef0d3{/solr,null,AVAILABLE}
   [junit4]   2> 31139 INFO  (jetty-launcher-3-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@51d53c93{HTTP/1.1,[http/1.1]}{127.0.0.1:50507}
   [junit4]   2> 31140 INFO  (jetty-launcher-3-thread-1) [    ] o.e.j.s.Server 
Started @31315ms
   [junit4]   2> 31142 INFO  (jetty-launcher-3-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@17c4e64d{HTTP/1.1,[http/1.1]}{127.0.0.1:45633}
   [junit4]   2> 31142 INFO  (jetty-launcher-3-thread-2) [    ] o.e.j.s.Server 
Started @31318ms
   [junit4]   2> 31143 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45633}
   [junit4]   2> 31148 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=50507}
   [junit4]   2> 31241 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.u.c.SSLCredentialProviderFactory Processing SSL Credential Provider 
chain: env;sysprop
   [junit4]   2> 31252 ERROR (jetty-launcher-3-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 31252 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 31252 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 31253 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 31254 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 31255 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-07-09T07:34:29.907Z
   [junit4]   2> 31253 ERROR (jetty-launcher-3-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 31256 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 31256 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 31256 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 31256 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 31257 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 31257 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-07-09T07:34:29.909Z
   [junit4]   2> 31353 INFO  (zkConnectionManagerCallback-8-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 31396 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 31408 INFO  (zkConnectionManagerCallback-10-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 31422 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 32364 INFO  (jetty-launcher-3-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46364/solr
   [junit4]   2> 32556 INFO  (zkConnectionManagerCallback-14-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 32650 INFO  (zkConnectionManagerCallback-16-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33716 INFO  (jetty-launcher-3-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46364/solr
   [junit4]   2> 33765 INFO  (zkConnectionManagerCallback-22-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33809 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 33814 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:45633_solr
   [junit4]   2> 33815 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.Overseer Overseer 
(id=74113691965128708-127.0.0.1:45633_solr-n_0000000000) starting
   [junit4]   2> 33859 INFO  (zkConnectionManagerCallback-24-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33879 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 33882 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:50507_solr
   [junit4]   2> 34028 INFO  (zkCallback-15-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 34029 INFO  (zkCallback-23-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 34133 INFO  (zkConnectionManagerCallback-31-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 34135 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 34172 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:46364/solr ready
   [junit4]   2> 34452 INFO  (zkConnectionManagerCallback-36-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 34465 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 34473 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:46364/solr ready
   [junit4]   2> 34492 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 34886 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 34886 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45633_solr
   [junit4]   2> 34897 INFO  (zkCallback-15-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 34930 INFO  (zkCallback-23-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 34946 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50507.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 34947 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50507.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 34947 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_50507.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 34970 INFO  (zkCallback-30-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 34975 INFO  (jetty-launcher-3-thread-1) 
[n:127.0.0.1:50507_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node1/.
   [junit4]   2> 35013 INFO  (zkCallback-35-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 35106 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 35498 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45633.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 35576 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45633.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 35605 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45633.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 35607 INFO  (jetty-launcher-3-thread-2) 
[n:127.0.0.1:45633_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node2/.
   [junit4]   2> 35992 INFO  (zkConnectionManagerCallback-40-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 36060 INFO  (zkConnectionManagerCallback-45-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 36071 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 36073 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46364/solr ready
   [junit4]   2> 36082 INFO  
(SUITE-TestPullReplica-seed#[460134BEE39B8E15]-worker) [    ] 
o.a.s.c.TestPullReplica Using legacyCloud?: false
   [junit4]   2> 37024 INFO  (qtp1795463531-43) [n:127.0.0.1:50507_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> 37057 INFO  (qtp1795463531-43) [n:127.0.0.1:50507_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=47
   [junit4]   2> 37226 INFO  
(TEST-TestPullReplica.testAddDocs-seed#[460134BEE39B8E15]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testAddDocs
   [junit4]   2> 37259 INFO  (qtp1795463531-41) [n:127.0.0.1:50507_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
pullReplicas=3&collection.configName=conf&maxShardsPerNode=100&name=pull_replica_test_add_docs&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 37300 INFO  (OverseerThreadFactory-32-thread-1) [    ] 
o.a.s.c.a.c.CreateCollectionCmd Create collection pull_replica_test_add_docs
   [junit4]   2> 37324 WARN  (OverseerThreadFactory-32-thread-1) [    ] 
o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 4 on collection 
pull_replica_test_add_docs is higher than the number of Solr instances 
currently live or live and part of your createNodeSet(2). It's unusual to run 
two replica of the same slice on the same Solr-instance.
   [junit4]   2> 37537 INFO  
(OverseerStateUpdate-74113691965128708-127.0.0.1:45633_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"pull_replica_test_add_docs",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"pull_replica_test_add_docs_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:45633/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 37559 INFO  
(OverseerStateUpdate-74113691965128708-127.0.0.1:45633_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"pull_replica_test_add_docs",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"pull_replica_test_add_docs_shard1_replica_p2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:50507/solr";,
   [junit4]   2>   "type":"PULL",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 37563 INFO  
(OverseerStateUpdate-74113691965128708-127.0.0.1:45633_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"pull_replica_test_add_docs",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"pull_replica_test_add_docs_shard1_replica_p4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:45633/solr";,
   [junit4]   2>   "type":"PULL",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 37566 INFO  
(OverseerStateUpdate-74113691965128708-127.0.0.1:45633_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"pull_replica_test_add_docs",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"pull_replica_test_add_docs_shard1_replica_p5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:50507/solr";,
   [junit4]   2>   "type":"PULL",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 37825 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr    
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.h.a.CoreAdminOperation 
core create command 
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p2&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL
   [junit4]   2> 37826 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr    
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.h.a.CoreAdminOperation 
core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p5&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL
   [junit4]   2> 37835 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr    
x:pull_replica_test_add_docs_shard1_replica_p5] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 37846 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr    
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation 
core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 37848 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr    
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.h.a.CoreAdminOperation 
core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p4&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL
   [junit4]   2> 38921 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 38921 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 38957 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 39017 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 39043 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.s.IndexSchema 
[pull_replica_test_add_docs_shard1_replica_p5] Schema name=minimal
   [junit4]   2> 39051 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.s.IndexSchema 
[pull_replica_test_add_docs_shard1_replica_p4] Schema name=minimal
   [junit4]   2> 39053 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.s.IndexSchema 
[pull_replica_test_add_docs_shard1_replica_p2] Schema name=minimal
   [junit4]   2> 39087 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 39088 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.CoreContainer Creating 
SolrCore 'pull_replica_test_add_docs_shard1_replica_p4' using configuration 
from collection pull_replica_test_add_docs, trusted=true
   [junit4]   2> 39088 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.s.IndexSchema 
[pull_replica_test_add_docs_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 39096 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 39101 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.CoreContainer Creating 
SolrCore 'pull_replica_test_add_docs_shard1_replica_p2' using configuration 
from collection pull_replica_test_add_docs, trusted=true
   [junit4]   2> 39120 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 39121 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'pull_replica_test_add_docs_shard1_replica_n1' using configuration 
from collection pull_replica_test_add_docs, trusted=true
   [junit4]   2> 39123 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_45633.solr.core.pull_replica_test_add_docs.shard1.replica_n1' (registry 
'solr.core.pull_replica_test_add_docs.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 39123 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 39123 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SolrCore 
[[pull_replica_test_add_docs_shard1_replica_n1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node2/pull_replica_test_add_docs_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node2/./pull_replica_test_add_docs_shard1_replica_n1/data/]
   [junit4]   2> 39124 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_45633.solr.core.pull_replica_test_add_docs.shard1.replica_p4' (registry 
'solr.core.pull_replica_test_add_docs.shard1.replica_p4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 39124 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 39125 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.SolrCore 
[[pull_replica_test_add_docs_shard1_replica_p4] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node2/pull_replica_test_add_docs_shard1_replica_p4],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node2/./pull_replica_test_add_docs_shard1_replica_p4/data/]
   [junit4]   2> 39126 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_50507.solr.core.pull_replica_test_add_docs.shard1.replica_p2' (registry 
'solr.core.pull_replica_test_add_docs.shard1.replica_p2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 39133 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.s.IndexSchema Loaded 
schema minimal/1.1 with uniqueid field id
   [junit4]   2> 39133 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.CoreContainer Creating 
SolrCore 'pull_replica_test_add_docs_shard1_replica_p5' using configuration 
from collection pull_replica_test_add_docs, trusted=true
   [junit4]   2> 39134 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_50507.solr.core.pull_replica_test_add_docs.shard1.replica_p5' (registry 
'solr.core.pull_replica_test_add_docs.shard1.replica_p5') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@6356814b
   [junit4]   2> 39134 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 39134 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.SolrCore 
[[pull_replica_test_add_docs_shard1_replica_p5] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node1/pull_replica_test_add_docs_shard1_replica_p5],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node1/./pull_replica_test_add_docs_shard1_replica_p5/data/]
   [junit4]   2> 39149 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 39149 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.SolrCore 
[[pull_replica_test_add_docs_shard1_replica_p2] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node1/pull_replica_test_add_docs_shard1_replica_p2],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplica_460134BEE39B8E15-001/tempDir-001/node1/./pull_replica_test_add_docs_shard1_replica_p2/data/]
   [junit4]   2> 39656 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 39657 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 39673 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@7adaa573[pull_replica_test_add_docs_shard1_replica_p2] main]
   [junit4]   2> 39682 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 39683 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 39684 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 39738 INFO  
(searcherExecutor-39-thread-1-processing-n:127.0.0.1:50507_solr 
x:pull_replica_test_add_docs_shard1_replica_p2 c:pull_replica_test_add_docs 
s:shard1 r:core_node6) [n:127.0.0.1:50507_solr c:pull_replica_test_add_docs 
s:shard1 r:core_node6 x:pull_replica_test_add_docs_shard1_replica_p2] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p2] Registered new 
searcher Searcher@7adaa573[pull_replica_test_add_docs_shard1_replica_p2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 39817 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.ZkController 
pull_replica_test_add_docs_shard1_replica_p2 starting background replication 
from leader
   [junit4]   2> 39819 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.ReplicateFromLeader 
Will start replication from leader with poll interval: 00:00:03
   [junit4]   2> 39883 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.h.ReplicationHandler Poll 
scheduled at an interval of 3000ms
   [junit4]   2> 39883 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 39988 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 39989 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 39997 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@4a32c0a3[pull_replica_test_add_docs_shard1_replica_p4] main]
   [junit4]   2> 40000 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 40000 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 40003 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 40010 INFO  
(searcherExecutor-38-thread-1-processing-n:127.0.0.1:45633_solr 
x:pull_replica_test_add_docs_shard1_replica_p4 c:pull_replica_test_add_docs 
s:shard1 r:core_node7) [n:127.0.0.1:45633_solr c:pull_replica_test_add_docs 
s:shard1 r:core_node7 x:pull_replica_test_add_docs_shard1_replica_p4] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p4] Registered new 
searcher Searcher@4a32c0a3[pull_replica_test_add_docs_shard1_replica_p4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 40015 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.ZkController 
pull_replica_test_add_docs_shard1_replica_p4 starting background replication 
from leader
   [junit4]   2> 40016 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.c.ReplicateFromLeader 
Will start replication from leader with poll interval: 00:00:03
   [junit4]   2> 40020 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.h.ReplicationHandler Poll 
scheduled at an interval of 3000ms
   [junit4]   2> 40021 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 40036 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 40036 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 40046 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@fa17bde[pull_replica_test_add_docs_shard1_replica_p5] main]
   [junit4]   2> 40061 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 40062 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 40063 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 40071 INFO  
(searcherExecutor-41-thread-1-processing-n:127.0.0.1:50507_solr 
x:pull_replica_test_add_docs_shard1_replica_p5 c:pull_replica_test_add_docs 
s:shard1 r:core_node8) [n:127.0.0.1:50507_solr c:pull_replica_test_add_docs 
s:shard1 r:core_node8 x:pull_replica_test_add_docs_shard1_replica_p5] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p5] Registered new 
searcher Searcher@fa17bde[pull_replica_test_add_docs_shard1_replica_p5] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 40098 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.ZkController 
pull_replica_test_add_docs_shard1_replica_p5 starting background replication 
from leader
   [junit4]   2> 40098 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.c.ReplicateFromLeader 
Will start replication from leader with poll interval: 00:00:03
   [junit4]   2> 40101 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.h.ReplicationHandler Poll 
scheduled at an interval of 3000ms
   [junit4]   2> 40101 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 40184 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 40184 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 40258 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 40258 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 40281 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@505c97cd[pull_replica_test_add_docs_shard1_replica_n1] main]
   [junit4]   2> 40283 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 40283 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 40284 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 40286 INFO  
(searcherExecutor-40-thread-1-processing-n:127.0.0.1:45633_solr 
x:pull_replica_test_add_docs_shard1_replica_n1 c:pull_replica_test_add_docs 
s:shard1 r:core_node3) [n:127.0.0.1:45633_solr c:pull_replica_test_add_docs 
s:shard1 r:core_node3 x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_n1] Registered new 
searcher Searcher@505c97cd[pull_replica_test_add_docs_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 40287 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.UpdateLog Could not 
find max version in index or recent updates, using new clock 1605497445615140864
   [junit4]   2> 40307 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/pull_replica_test_add_docs/terms/shard1 to 
Terms{values={core_node3=0}, version=0}
   [junit4]   2> 40360 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 40360 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 40360 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SyncStrategy Sync 
replicas to 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/
   [junit4]   2> 40362 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SyncStrategy Sync 
Success - now sync replicas to me
   [junit4]   2> 40362 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/ has 
no replicas
   [junit4]   2> 40362 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 40565 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/ shard1
   [junit4]   2> 40676 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 40682 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Replica core_node3 is leader but it's state is down, 
skipping replication
   [junit4]   2> 40683 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Replica core_node3 is leader but it's state is down, 
skipping replication
   [junit4]   2> 40688 INFO  (qtp1225175455-53) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2842
   [junit4]   2> 40781 INFO  (zkCallback-15-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/pull_replica_test_add_docs/state.json] for collection 
[pull_replica_test_add_docs] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 40889 INFO  (qtp1795463531-39) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p2&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL}
 status=0 QTime=3068
   [junit4]   2> 41010 INFO  (zkCallback-23-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/pull_replica_test_add_docs/state.json] for collection 
[pull_replica_test_add_docs] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 41011 INFO  (zkCallback-15-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/pull_replica_test_add_docs/state.json] for collection 
[pull_replica_test_add_docs] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 41040 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node7 
x:pull_replica_test_add_docs_shard1_replica_p4] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p4&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL}
 status=0 QTime=3191
   [junit4]   2> 41106 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node8 
x:pull_replica_test_add_docs_shard1_replica_p5] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=pull_replica_test_add_docs_shard1_replica_p5&action=CREATE&numShards=1&collection=pull_replica_test_add_docs&shard=shard1&wt=javabin&version=2&replicaType=PULL}
 status=0 QTime=3282
   [junit4]   2> 41125 INFO  
(OverseerCollectionConfigSetProcessor-74113691965128708-127.0.0.1:45633_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 41128 INFO  (qtp1795463531-41) [n:127.0.0.1:50507_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> 41206 INFO  (zkCallback-23-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/pull_replica_test_add_docs/state.json] for collection 
[pull_replica_test_add_docs] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 41208 INFO  (zkCallback-15-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/pull_replica_test_add_docs/state.json] for collection 
[pull_replica_test_add_docs] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 42129 INFO  (qtp1795463531-41) [n:127.0.0.1:50507_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={pullReplicas=3&collection.configName=conf&maxShardsPerNode=100&name=pull_replica_test_add_docs&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2}
 status=0 QTime=4869
   [junit4]   2> 42433 INFO  (qtp1225175455-49) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/pull_replica_test_add_docs/terms/shard1 to 
Terms{values={core_node3=1}, version=1}
   [junit4]   2> 42434 INFO  (qtp1225175455-49) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1 (1605497447714390016)]} 0 208
   [junit4]   2> 42438 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/
   [junit4]   2> 42458 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1605497447891599360,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 42458 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@15e0d8f1 
commitCommandVersion:1605497447891599360
   [junit4]   2> 42480 INFO  (qtp1225175455-52) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=39
   [junit4]   2> 42481 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 42481 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 42481 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 42481 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 42481 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher New index in Master. Deleting mine...
   [junit4]   2> 42485 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 42485 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@53e1aae9 commitCommandVersion:0
   [junit4]   2> 42933 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Last replication failed, so I'll force replication
   [junit4]   2> 42933 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/
   [junit4]   2> 42942 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 42945 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 42945 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 42945 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 42945 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 42945 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher New index in Master. Deleting mine...
   [junit4]   2> 42948 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7d6cda03[pull_replica_test_add_docs_shard1_replica_p5] main]
   [junit4]   2> 42949 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 42951 INFO  (searcherExecutor-41-thread-1) [    ] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p5] Registered new 
searcher Searcher@7d6cda03[pull_replica_test_add_docs_shard1_replica_p5] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 42957 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 42957 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76932e16 commitCommandVersion:0
   [junit4]   2> 42965 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3969d48f[pull_replica_test_add_docs_shard1_replica_p2] main]
   [junit4]   2> 42968 INFO  (searcherExecutor-39-thread-1) [    ] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p2] Registered new 
searcher Searcher@3969d48f[pull_replica_test_add_docs_shard1_replica_p2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 42968 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 43017 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Last replication failed, so I'll force replication
   [junit4]   2> 43017 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
http://127.0.0.1:45633/solr/pull_replica_test_add_docs_shard1_replica_n1/
   [junit4]   2> 43029 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 43031 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 43031 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 43031 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 43031 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 43031 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.h.IndexFetcher New index in Master. Deleting mine...
   [junit4]   2> 43032 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 43032 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6ba5856b commitCommandVersion:0
   [junit4]   2> 43035 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5c310a6b[pull_replica_test_add_docs_shard1_replica_p4] main]
   [junit4]   2> 43036 INFO  (indexFetcher-53-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 43038 INFO  (searcherExecutor-38-thread-1) [    ] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_p4] Registered new 
searcher Searcher@5c310a6b[pull_replica_test_add_docs_shard1_replica_p4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 43112 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@ae8fd56[pull_replica_test_add_docs_shard1_replica_n1] main]
   [junit4]   2> 43115 INFO  
(searcherExecutor-40-thread-1-processing-n:127.0.0.1:45633_solr 
x:pull_replica_test_add_docs_shard1_replica_n1 c:pull_replica_test_add_docs 
s:shard1 r:core_node3) [n:127.0.0.1:45633_solr c:pull_replica_test_add_docs 
s:shard1 r:core_node3 x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.c.SolrCore [pull_replica_test_add_docs_shard1_replica_n1] Registered new 
searcher Searcher@ae8fd56[pull_replica_test_add_docs_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
   [junit4]   2> 43119 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 43119 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/update 
params={_stateVer_=pull_replica_test_add_docs:7&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 662
   [junit4]   2> 43143 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=3
   [junit4]   2> 43175 INFO  (qtp1795463531-42) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=15
   [junit4]   2> 43279 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43384 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43498 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43602 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43705 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43809 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 43919 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44085 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44188 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44296 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44399 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44530 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44635 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44747 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 44852 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45046 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45149 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45254 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45357 INFO  (qtp1795463531-46) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45440 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 45445 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 45445 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1531121681111
   [junit4]   2> 45445 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 45445 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 1531121681258
   [junit4]   2> 45445 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 45574 INFO  (qtp1795463531-42) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45687 INFO  (qtp1795463531-42) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45457 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=10
   [junit4]   2> 45730 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 18
   [junit4]   2> 45732 INFO  (indexFetcher-57-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=true) to 
MockDirectoryWrapper(RAMDirectory@76bd41cf 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e59cc25)
   [junit4]   2> 45760 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=5
   [junit4]   2> 45796 INFO  (qtp1795463531-42) [n:127.0.0.1:50507_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node6 
x:pull_replica_test_add_docs_shard1_replica_p2] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_p2]  webapp=/solr path=/select 
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 45828 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45839 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45846 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_MockRandom_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45850 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.pos&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45852 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45857 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45860 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45863 INFO  (qtp1225175455-52) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 45864 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 45864 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1531121681111
   [junit4]   2> 45864 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 45864 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 1531121681609
   [junit4]   2> 45864 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 45866 INFO  (qtp1225175455-52) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=1
   [junit4]   2> 45867 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 18
   [junit4]   2> 45867 INFO  (indexFetcher-48-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=true) to 
MockDirectoryWrapper(RAMDirectory@3578337b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5d08163f)
   [junit4]   2> 45869 INFO  (qtp1225175455-48) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45886 INFO  (qtp1225175455-55) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45889 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.dii&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45892 INFO  (qtp1225175455-52) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_docs_shard1_replica_n1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 45894 INFO  (qtp1225175455-54) [n:127.0.0.1:45633_solr 
c:pull_replica_test_add_docs s:shard1 r:core_node3 
x:pull_replica_test_add_docs_shard1_replica_n1] o.a.s.c.S.Request 
[pull_replica_test_add_d

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

jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null23856135
     [copy] Copying 241 files to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null23856135
   [delete] Deleting directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/null23856135

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: 
org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath]         confs: [default]
[ivy:cachepath]         found 
org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath]         found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath]         found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath]         found org.apache.httpcomponents#httpclient;4.3.6 in 
public
[ivy:cachepath]         found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath]         found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath]         found commons-codec#commons-codec;1.6 in public
[ivy:cachepath]         found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 97ms :: artifacts dl 6ms
        ---------------------------------------------------------------------
        |                  |            modules            ||   artifacts   |
        |       conf       | number| search|dwnlded|evicted|| number|dwnlded|
        ---------------------------------------------------------------------
        |      default     |   8   |   0   |   0   |   0   ||   8   |   0   |
        ---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for 
further details.
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 407 minutes 20 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
        at hudson.FilePath$34.hasMatch(FilePath.java:2678)
        at hudson.FilePath$34.invoke(FilePath.java:2557)
        at hudson.FilePath$34.invoke(FilePath.java:2547)
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
                at 
hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
                at 
hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
                at hudson.remoting.Channel.call(Channel.java:955)
                at hudson.FilePath.act(FilePath.java:1036)
                at hudson.FilePath.act(FilePath.java:1025)
                at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
                at 
hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
                at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
                at 
hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
                at hudson.model.Build$BuildExecution.post2(Build.java:186)
                at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
                at hudson.model.Run.execute(Run.java:1819)
                at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
                at 
hudson.model.ResourceController.execute(ResourceController.java:97)
                at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
        at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
        at hudson.remoting.UserRequest.perform(UserRequest.java:212)
        at hudson.remoting.UserRequest.perform(UserRequest.java:54)
        at hudson.remoting.Request$2.run(Request.java:369)
        at 
hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no 
matches found within 10000
        at hudson.FilePath.act(FilePath.java:1038)
        at hudson.FilePath.act(FilePath.java:1025)
        at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
        at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
        at 
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
        at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
        at hudson.model.Build$BuildExecution.post2(Build.java:186)
        at 
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
        at hudson.model.Run.execute(Run.java:1819)
        at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
        at hudson.model.ResourceController.execute(ResourceController.java:97)
        at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern 
"**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to