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

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

Error Message:


Stack Trace:
java.util.concurrent.TimeoutException
        at 
__randomizedtesting.SeedInfo.seed([8390199FE3D19272:BC426454D2DFF8A]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1267)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.waitForState(CloudSolrClient.java:438)
        at 
org.apache.solr.cloud.CleanupOldIndexTest.test(CleanupOldIndexTest.java:114)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.client.solrj.TestLBHttpSolrClient.testTwoServers

Error Message:
Error from server at https://127.0.0.1:41551/solr/collection1: SolrCore 
'collection1' is not available due to init failure: null

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at https://127.0.0.1:41551/solr/collection1: SolrCore 'collection1' 
is not available due to init failure: null
        at 
__randomizedtesting.SeedInfo.seed([DE5B5C126E5225D5:7EB1F29FB5190BF5]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:627)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:690)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:638)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
        at 
org.apache.solr.client.solrj.TestLBHttpSolrClient.testTwoServers(TestLBHttpSolrClient.java:191)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11736 lines...]
   [junit4] Suite: org.apache.solr.cloud.CleanupOldIndexTest
   [junit4]   2> 1517730 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/init-core-data-001
   [junit4]   2> 1517731 WARN  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
   [junit4]   2> 1517731 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1517732 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1517733 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001
   [junit4]   2> 1517733 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1517741 INFO  (Thread-1185) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1517741 INFO  (Thread-1185) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1517759 ERROR (Thread-1185) [    ] 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> 1517841 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42517
   [junit4]   2> 1518043 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1518044 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1518045 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@22947370{/solr,null,AVAILABLE}
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@72018bc9{SSL,[ssl, 
http/1.1]}{127.0.0.1:35400}
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.e.j.s.Server Started @1526839ms
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35400}
   [junit4]   2> 1518059 ERROR (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1518059 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-08-08T06:13:41.138Z
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@b496b9f{/solr,null,AVAILABLE}
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@6f305c6e{SSL,[ssl, 
http/1.1]}{127.0.0.1:34336}
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.e.j.s.Server Started @1526958ms
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34336}
   [junit4]   2> 1518177 ERROR (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1518177 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1518178 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-08-08T06:13:41.256Z
   [junit4]   2> 1518221 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1518232 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1518274 INFO  (jetty-launcher-1511-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42517/solr
   [junit4]   2> 1518277 INFO  (jetty-launcher-1511-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42517/solr
   [junit4]   2> 1518610 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1518621 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1518635 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34336_solr
   [junit4]   2> 1518637 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1518643 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1518675 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:35400_solr
   [junit4]   2> 1518740 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.Overseer Overseer 
(id=98446397985325062-127.0.0.1:35400_solr-n_0000000000) starting
   [junit4]   2> 1518960 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34336.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1518976 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34336.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1518976 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34336.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1518977 INFO  (jetty-launcher-1511-thread-1) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node1/.
   [junit4]   2> 1519028 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35400_solr
   [junit4]   2> 1519093 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1519113 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1519444 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35400.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1519460 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35400.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1519460 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35400.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1519461 INFO  (jetty-launcher-1511-thread-2) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node2/.
   [junit4]   2> 1519572 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1519609 INFO  
(SUITE-CleanupOldIndexTest-seed#[8390199FE3D19272]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42517/solr ready
   [junit4]   2> 1519755 INFO  
(TEST-CleanupOldIndexTest.test-seed#[8390199FE3D19272]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1520020 INFO  (qtp654377956-6560) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
async=31d41188-e421-4fe9-8b10-643b82861e87&replicationFactor=2&collection.configName=conf1&name=oldindextest&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1520022 INFO  (qtp654377956-6560) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=31d41188-e421-4fe9-8b10-643b82861e87&replicationFactor=2&collection.configName=conf1&name=oldindextest&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 1520079 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1520177 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=97
   [junit4]   2> 1520233 INFO  
(OverseerThreadFactory-2104-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
oldindextest
   [junit4]   2> 1520489 INFO  
(OverseerStateUpdate-98446397985325062-127.0.0.1:35400_solr-n_0000000000) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"oldindextest",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"oldindextest_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:35400/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1520526 INFO  
(OverseerStateUpdate-98446397985325062-127.0.0.1:35400_solr-n_0000000000) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"oldindextest",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"oldindextest_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:34336/solr";,
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1520855 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=31d41188-e421-4fe9-8b10-643b82861e872282132789884036&coreNodeName=core_node3&name=oldindextest_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin}
 status=0 QTime=16
   [junit4]   2> 1520883 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=31d41188-e421-4fe9-8b10-643b82861e872282132789884036&coreNodeName=core_node3&name=oldindextest_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
   [junit4]   2> 1520883 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1521053 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&coreNodeName=core_node4&name=oldindextest_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin}
 status=0 QTime=3
   [junit4]   2> 1521054 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=oldindextest&version=2&replicaType=NRT&async=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&coreNodeName=core_node4&name=oldindextest_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin
   [junit4]   2> 1521054 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1521079 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755
   [junit4]   2> 1521079 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1521178 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1521228 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=49
   [junit4]   2> 1521338 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1521345 INFO  
(zkCallback-1523-thread-2-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1521346 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1521349 INFO  
(zkCallback-1522-thread-2-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1522118 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
7.1.0
   [junit4]   2> 1522125 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755
   [junit4]   2> 1522125 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1522269 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
7.1.0
   [junit4]   2> 1522276 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1522301 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica_n1] Schema name=cloud-dynamic
   [junit4]   2> 1522338 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica_n2] Schema name=cloud-dynamic
   [junit4]   2> 1522340 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=64
   [junit4]   2> 1522428 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema 
cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1522429 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 
'oldindextest_shard1_replica_n2' using configuration from collection 
oldindextest, trusted=true
   [junit4]   2> 1522429 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34336.solr.core.oldindextest.shard1.replica_n2' (registry 
'solr.core.oldindextest.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1522429 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1522448 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1522448 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 
'oldindextest_shard1_replica_n1' using configuration from collection 
oldindextest, trusted=true
   [junit4]   2> 1522449 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35400.solr.core.oldindextest.shard1.replica_n1' (registry 
'solr.core.oldindextest.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2d04db1a
   [junit4]   2> 1522449 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1522449 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.c.SolrCore 
[[oldindextest_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node2/oldindextest_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node2/./oldindextest_shard1_replica_n1/data/]
   [junit4]   2> 1522462 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.SolrCore 
[[oldindextest_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node1/oldindextest_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_8390199FE3D19272-001/tempDir-001/node1/./oldindextest_shard1_replica_n2/data/]
   [junit4]   2> 1522935 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1522935 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1522936 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1522936 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1522998 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@74311574[oldindextest_shard1_replica_n1] main]
   [junit4]   2> 1523006 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1523006 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1523006 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1523006 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1523009 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4ac8c340[oldindextest_shard1_replica_n2] main]
   [junit4]   2> 1523009 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1523009 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1523043 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1523045 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1575142373325799424
   [junit4]   2> 1523046 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1523093 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1523093 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1523093 INFO  
(parallelCoreAdminExecutor-2106-thread-1-processing-n:127.0.0.1:35400_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE) 
[n:127.0.0.1:35400_solr c:oldindextest s:shard1 r:core_node3 
x:oldindextest_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1575142373376131072
   [junit4]   2> 1523160 INFO  
(searcherExecutor-2108-thread-1-processing-x:oldindextest_shard1_replica_n2 
r:core_node4 31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE 
n:127.0.0.1:34336_solr s:shard1 c:oldindextest) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.c.SolrCore [oldindextest_shard1_replica_n2] Registered new searcher 
Searcher@4ac8c340[oldindextest_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1523160 INFO  
(searcherExecutor-2109-thread-1-processing-x:oldindextest_shard1_replica_n1 
r:core_node3 31d41188-e421-4fe9-8b10-643b82861e872282132789884036 CREATE 
n:127.0.0.1:35400_solr s:shard1 c:oldindextest) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.c.SolrCore [oldindextest_shard1_replica_n1] Registered new searcher 
Searcher@74311574[oldindextest_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1523161 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755
   [junit4]   2> 1523161 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1523194 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 1523194 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be 
the new leader - try and sync
   [junit4]   2> 1523194 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/
   [junit4]   2> 1523194 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: 
core=oldindextest_shard1_replica_n2 url=https://127.0.0.1:34336/solr START 
replicas=[https://127.0.0.1:35400/solr/oldindextest_shard1_replica_n1/] 
nUpdates=100
   [junit4]   2> 1523208 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: 
core=oldindextest_shard1_replica_n2 url=https://127.0.0.1:34336/solr DONE.  We 
have no versions.  sync failed.
   [junit4]   2> 1523229 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.c.S.Request [oldindextest_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=15
   [junit4]   2> 1523241 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attempt to sync 
with shard failed, moving to the next candidate
   [junit4]   2> 1523241 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext We failed 
sync, but we have no versions - we can't sync in that case - we were active 
before, so become leader anyway
   [junit4]   2> 1523241 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 1523243 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the 
new leader: https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/ shard1
   [junit4]   2> 1523343 INFO  (qtp654377956-6560) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1523344 INFO  (qtp654377956-6560) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1523446 INFO  
(zkCallback-1522-thread-2-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523446 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523446 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523446 INFO  
(zkCallback-1523-thread-2-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523501 INFO  
(parallelCoreAdminExecutor-2103-thread-1-processing-n:127.0.0.1:34336_solr 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755 CREATE) 
[n:127.0.0.1:34336_solr c:oldindextest s:shard1 r:core_node4 
x:oldindextest_shard1_replica_n2] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 1523740 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523740 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523740 INFO  
(zkCallback-1523-thread-2-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1523744 INFO  
(zkCallback-1522-thread-2-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1524178 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132870437755
   [junit4]   2> 1524178 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132870437755&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1524192 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036
   [junit4]   2> 1524192 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132789884036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1524354 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1524354 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1524407 INFO  
(zkCallback-1523-thread-2-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1524407 INFO  
(zkCallback-1523-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1524407 INFO  
(zkCallback-1522-thread-2-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1524407 INFO  
(zkCallback-1522-thread-1-processing-n:127.0.0.1:34336_solr) 
[n:127.0.0.1:34336_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/oldindextest/state.json] for collection [oldindextest] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 1525202 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
31d41188-e421-4fe9-8b10-643b82861e872282132789884036
   [junit4]   2> 1525202 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=31d41188-e421-4fe9-8b10-643b82861e872282132789884036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1525230 INFO  
(OverseerThreadFactory-2104-thread-1-processing-n:127.0.0.1:35400_solr) 
[n:127.0.0.1:35400_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1525361 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1525385 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=23
   [junit4]   2> 1525385 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1525386 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=31d41188-e421-4fe9-8b10-643b82861e87&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1525501 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-0
 (1575142375784710144)]} 0 2
   [junit4]   2> 1525506 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-0 
(1575142375784710144)]} 0 116
   [junit4]   2> 1525508 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-0
 (-1575142375907393536)]} 0 0
   [junit4]   2> 1525508 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-0 
(-1575142375907393536)]} 0 1
   [junit4]   2> 1525510 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-1
 (1575142375908442112)]} 0 0
   [junit4]   2> 1525510 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-1 
(1575142375908442112)]} 0 1
   [junit4]   2> 1525539 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-2
 (1575142375924170752)]} 0 13
   [junit4]   2> 1525539 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-2 
(1575142375924170752)]} 0 16
   [junit4]   2> 1525544 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-3
 (1575142375941996544)]} 0 3
   [junit4]   2> 1525544 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-3 
(1575142375941996544)]} 0 4
   [junit4]   2> 1525600 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-4
 (1575142375947239424)]} 0 33
   [junit4]   2> 1525600 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-4 
(1575142375947239424)]} 0 55
   [junit4]   2> 1525605 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-5
 (1575142376009105408)]} 0 0
   [junit4]   2> 1525605 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-5 
(1575142376009105408)]} 0 1
   [junit4]   2> 1525607 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-6
 (1575142376011202560)]} 0 0
   [junit4]   2> 1525607 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-6 
(1575142376011202560)]} 0 1
   [junit4]   2> 1525652 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-4
 (-1575142376012251136)]} 0 13
   [junit4]   2> 1525653 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-4 
(-1575142376012251136)]} 0 45
   [junit4]   2> 1525673 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-7
 (1575142376060485632)]} 0 0
   [junit4]   2> 1525674 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-7 
(1575142376060485632)]} 0 20
   [junit4]   2> 1525675 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-8
 (1575142376082505728)]} 0 0
   [junit4]   2> 1525675 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-8 
(1575142376082505728)]} 0 1
   [junit4]   2> 1525676 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-5
 (-1575142376084602880)]} 0 0
   [junit4]   2> 1525705 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-5 
(-1575142376084602880)]} 0 29
   [junit4]   2> 1525707 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-9
 (1575142376116060160)]} 0 0
   [junit4]   2> 1525707 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-9 
(1575142376116060160)]} 0 1
   [junit4]   2> 1525708 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-6
 (-1575142376117108736)]} 0 0
   [junit4]   2> 1525708 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-6 
(-1575142376117108736)]} 0 1
   [junit4]   2> 1525755 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-10
 (1575142376119205888)]} 0 1
   [junit4]   2> 1525756 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-10 
(1575142376119205888)]} 0 46
   [junit4]   2> 1525774 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-8
 (-1575142376178974720)]} 0 7
   [junit4]   2> 1525774 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-8 
(-1575142376178974720)]} 0 8
   [junit4]   2> 1525776 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-11
 (1575142376188411904)]} 0 0
   [junit4]   2> 1525776 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-11 
(1575142376188411904)]} 0 1
   [junit4]   2> 1525777 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-12
 (1575142376189460480)]} 0 0
   [junit4]   2> 1525777 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-12 
(1575142376189460480)]} 0 0
   [junit4]   2> 1525791 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-13
 (1575142376191557632)]} 0 0
   [junit4]   2> 1525791 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-13 
(1575142376191557632)]} 0 13
   [junit4]   2> 1525792 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-14
 (1575142376206237696)]} 0 0
   [junit4]   2> 1525793 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-14 
(1575142376206237696)]} 0 1
   [junit4]   2> 1525794 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-10
 (-1575142376207286272)]} 0 0
   [junit4]   2> 1525794 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-10 
(-1575142376207286272)]} 0 1
   [junit4]   2> 1525824 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-15
 (1575142376238743552)]} 0 0
   [junit4]   2> 1525825 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-15 
(1575142376238743552)]} 0 1
   [junit4]   2> 1525826 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-12
 (-1575142376240840704)]} 0 0
   [junit4]   2> 1525826 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-12 
(-1575142376240840704)]} 0 1
   [junit4]   2> 1525863 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-16
 (1575142376241889280)]} 0 24
   [junit4]   2> 1525864 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-16 
(1575142376241889280)]} 0 37
   [junit4]   2> 1525865 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-14
 (-1575142376282783744)]} 0 0
   [junit4]   2> 1525865 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-14 
(-1575142376282783744)]} 0 0
   [junit4]   2> 1525867 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-17
 (1575142376283832320)]} 0 0
   [junit4]   2> 1525867 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-17 
(1575142376283832320)]} 0 1
   [junit4]   2> 1525880 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-18
 (1575142376297463808)]} 0 0
   [junit4]   2> 1525896 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-18 
(1575142376297463808)]} 0 28
   [junit4]   2> 1525901 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-16
 (-1575142376319483904)]} 0 0
   [junit4]   2> 1525901 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-16 
(-1575142376319483904)]} 0 0
   [junit4]   2> 1525902 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-19
 (1575142376321581056)]} 0 0
   [junit4]   2> 1525902 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-19 
(1575142376321581056)]} 0 1
   [junit4]   2> 1525903 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-19
 (-1575142376322629632)]} 0 0
   [junit4]   2> 1525904 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-19 
(-1575142376322629632)]} 0 0
   [junit4]   2> 1525932 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-20
 (1575142376336261120)]} 0 15
   [junit4]   2> 1525933 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-20 
(1575142376336261120)]} 0 16
   [junit4]   2> 1525949 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-21
 (1575142376355135488)]} 0 14
   [junit4]   2> 1525949 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-21 
(1575142376355135488)]} 0 15
   [junit4]   2> 1525950 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-22
 (1575142376371912704)]} 0 0
   [junit4]   2> 1525951 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-22 
(1575142376371912704)]} 0 1
   [junit4]   2> 1525952 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-23
 (1575142376372961280)]} 0 0
   [junit4]   2> 1525952 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-23 
(1575142376372961280)]} 0 1
   [junit4]   2> 1525982 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-24
 (1575142376405467136)]} 0 0
   [junit4]   2> 1525983 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-24 
(1575142376405467136)]} 0 1
   [junit4]   2> 1526072 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-25
 (1575142376498790400)]} 0 0
   [junit4]   2> 1526072 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-25 
(1575142376498790400)]} 0 1
   [junit4]   2> 1526074 INFO  (qtp654377956-6555) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-26
 (1575142376500887552)]} 0 1
   [junit4]   2> 1526094 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-26 
(1575142376500887552)]} 0 21
   [junit4]   2> 1526108 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-27
 (1575142376532344832)]} 0 0
   [junit4]   2> 1526108 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-27 
(1575142376532344832)]} 0 5
   [junit4]   2> 1526109 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-26
 (-1575142376538636288)]} 0 0
   [junit4]   2> 1526110 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-26 
(-1575142376538636288)]} 0 0
   [junit4]   2> 1526124 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-28
 (1575142376539684864)]} 0 0
   [junit4]   2> 1526124 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-28 
(1575142376539684864)]} 0 14
   [junit4]   2> 1526125 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-28
 (-1575142376555413504)]} 0 0
   [junit4]   2> 1526125 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-28 
(-1575142376555413504)]} 0 0
   [junit4]   2> 1526126 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-29
 (1575142376556462080)]} 0 0
   [junit4]   2> 1526127 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-29 
(1575142376556462080)]} 0 0
   [junit4]   2> 1526140 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-30
 (1575142376557510656)]} 0 0
   [junit4]   2> 1526141 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-30 
(1575142376557510656)]} 0 13
   [junit4]   2> 1526172 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-31
 (1575142376572190720)]} 0 14
   [junit4]   2> 1526173 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-31 
(1575142376572190720)]} 0 31
   [junit4]   2> 1526174 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-31
 (-1575142376605745152)]} 0 0
   [junit4]   2> 1526174 INFO  (qtp2039208178-6569) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-31 
(-1575142376605745152)]} 0 1
   [junit4]   2> 1526175 INFO  (qtp654377956-6554) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-32
 (1575142376607842304)]} 0 0
   [junit4]   2> 1526176 INFO  (qtp2039208178-6568) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-32 
(1575142376607842304)]} 0 1
   [junit4]   2> 1526189 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-32
 (-1575142376621473792)]} 0 0
   [junit4]   2> 1526189 INFO  (qtp2039208178-6566) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-32 
(-1575142376621473792)]} 0 1
   [junit4]   2> 1526191 INFO  (qtp654377956-6561) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-33
 (1575142376623570944)]} 0 0
   [junit4]   2> 1526191 INFO  (qtp2039208178-6571) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-33 
(1575142376623570944)]} 0 1
   [junit4]   2> 1526205 INFO  (qtp654377956-6558) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-34
 (1575142376624619520)]} 0 12
   [junit4]   2> 1526205 INFO  (qtp2039208178-6572) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-34 
(1575142376624619520)]} 0 13
   [junit4]   2> 1526221 INFO  (qtp654377956-6557) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{delete=[1-34
 (-1575142376640348160)]} 0 0
   [junit4]   2> 1526221 INFO  (qtp2039208178-6565) [n:127.0.0.1:34336_solr 
c:oldindextest s:shard1 r:core_node4 x:oldindextest_shard1_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-34 
(-1575142376640348160)]} 0 16
   [junit4]   2> 1526223 INFO  (qtp654377956-6559) [n:127.0.0.1:35400_solr 
c:oldindextest s:shard1 r:core_node3 x:oldindextest_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:34336/solr/oldindextest_shard1_replica_n2/&wt=javabin&version=2}{add=[1-35
 (1575142376657125376)]} 0 0
   [junit4]   2

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

g.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: Error opening 
new searcher
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.<init>(SolrCore.java:988)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.<init>(SolrCore.java:843)
   [junit4]   2>        at 
org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:980)
   [junit4]   2>        ... 32 more
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: Error opening 
new searcher
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2065)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:2185)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.initSearcher(SolrCore.java:1071)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.<init>(SolrCore.java:960)
   [junit4]   2>        ... 34 more
   [junit4]   2> Caused by: org.apache.lucene.store.LockObtainFailedException: 
Lock held by this virtual machine: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_DE5B5C126E5225D5-001/instance-1-001/collection1/data/index/write.lock
   [junit4]   2>        at 
org.apache.lucene.store.NativeFSLockFactory.obtainFSLock(NativeFSLockFactory.java:127)
   [junit4]   2>        at 
org.apache.lucene.store.FSLockFactory.obtainLock(FSLockFactory.java:41)
   [junit4]   2>        at 
org.apache.lucene.store.BaseDirectory.obtainLock(BaseDirectory.java:45)
   [junit4]   2>        at 
org.apache.lucene.store.FilterDirectory.obtainLock(FilterDirectory.java:104)
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.obtainLock(MockDirectoryWrapper.java:1049)
   [junit4]   2>        at 
org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:947)
   [junit4]   2>        at 
org.apache.solr.update.SolrIndexWriter.<init>(SolrIndexWriter.java:119)
   [junit4]   2>        at 
org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:94)
   [junit4]   2>        at 
org.apache.solr.update.DefaultSolrCoreState.createMainIndexWriter(DefaultSolrCoreState.java:248)
   [junit4]   2>        at 
org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:122)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2026)
   [junit4]   2>        ... 37 more
   [junit4]   2> 
   [junit4]   2> 99070 INFO  (qtp1142136926-455) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} 
hits=10 status=0 QTime=784
   [junit4]   2> 99564 INFO  (qtp1142136926-461) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} 
hits=10 status=0 QTime=0
   [junit4]   2> 99606 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2fc30918{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 99606 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1645067322
   [junit4]   2> 99606 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 99608 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 99609 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 99670 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 99699 INFO  (coreCloseExecutor-204-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@16dcce13
   [junit4]   2> 99867 INFO  (coreCloseExecutor-204-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=383569427
   [junit4]   2> 100171 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@69c10c9d{/solr,null,UNAVAILABLE}
   [junit4]   2> 100187 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@332ce65b{SSL,[ssl, 
http/1.1]}{127.0.0.1:45126}
   [junit4]   2> 100187 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=189646002
   [junit4]   2> 100187 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 100187 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 100187 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 100206 INFO  (coreCloseExecutor-206-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1336c770
   [junit4]   2> 100292 INFO  (coreCloseExecutor-206-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=322357104
   [junit4]   2> 100349 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@48a8314e{/solr,null,UNAVAILABLE}
   [junit4]   2> 100410 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@debe138{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 100410 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=745170613
   [junit4]   2> 100410 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 100410 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 100410 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 100411 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 100426 INFO  (coreCloseExecutor-208-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@50b77a1
   [junit4]   2> 100605 INFO  (coreCloseExecutor-208-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=84637601
   [junit4]   2> 100780 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@52606cdf{/solr,null,UNAVAILABLE}
   [junit4]   2> 100786 INFO  
(TEST-TestLBHttpSolrClient.testReliability-seed#[DE5B5C126E5225D5]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testReliability
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.TestLBHttpSolrClient_DE5B5C126E5225D5-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{name=PostingsFormat(name=Memory), id=PostingsFormat(name=Memory)}, 
docValues:{}, maxPointsInLeafNode=1791, maxMBSortInHeap=6.4884894578395045, 
sim=RandomSimilarity(queryNorm=false): {}, locale=lv-LV, 
timezone=Pacific/Kiritimati
   [junit4]   2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 
1.8.0_144 (64-bit)/cpus=4,threads=4,free=158717456,total=342884352
   [junit4]   2> NOTE: All tests run in this JVM: 
[HttpSolrClientSSLAuthConPoolTest, TestNamedListCodec, CubedRootEvaluatorTest, 
ExclusiveOrEvaluatorTest, TestValidatingJsonMap, GraphTest, 
HyperbolicSineEvaluatorTest, TestLBHttpSolrClient]
   [junit4] Completed [16/135 (1!)] on J0 in 45.34s, 3 tests, 1 error <<< 
FAILURES!

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

Reply via email to