[JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_102) - Build # 1807 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1807/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseSerialGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.CleanupOldIndexTest

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

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


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

Error Message:
Test abandoned because suite timeout was reached.

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




Build Log:
[...truncated 12794 lines...]
   [junit4] Suite: org.apache.solr.cloud.CleanupOldIndexTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/init-core-data-001
   [junit4]   2> 1447903 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-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> 1447905 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1447905 INFO  (Thread-2833) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1447906 INFO  (Thread-2833) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1448006 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:46267
   [junit4]   2> 1448013 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448026 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1448026 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1448027 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@d2b776{/solr,null,AVAILABLE}
   [junit4]   2> 1448027 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@25efe{/solr,null,AVAILABLE}
   [junit4]   2> 1448031 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@100c91{SSL,[ssl, 
http/1.1]}{127.0.0.1:39209}
   [junit4]   2> 1448031 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@19c9dfb{SSL,[ssl, 
http/1.1]}{127.0.0.1:41375}
   [junit4]   2> 1448031 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.e.j.s.Server Started @1449499ms
   [junit4]   2> 1448032 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.e.j.s.Server Started @1449500ms
   [junit4]   2> 1448032 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39209}
   [junit4]   2> 1448032 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41375}
   [junit4]   2> 1448042 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448042 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448042 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1448042 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1448072 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x157664274a30002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1448074 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1448074 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1448075 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46267/solr
   [junit4]   2> 1448075 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46267/solr
   [junit4]   2> 1448076 INFO  (jetty-launcher-2061-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448076 INFO  (jetty-launcher-2061-thread-2) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448077 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x157664274a30004, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1448078 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 1448078 INFO  (jetty-launcher-2061-thread-2) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 1448093 INFO  (jetty-launcher-2061-thread-2) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 1448093 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 1448093 INFO  (jetty-launcher-2061-thread-2) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 1448093 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 1448097 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:39209_solr
   [junit4]   2> 1448097 INFO  (jetty-launcher-2061-thread-2) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41375_solr
   [junit4]   2> 1448097 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.Overseer Overseer 
(id=96658352622469125-127.0.0.1:39209_solr-n_0000000000) starting
   [junit4]   2> 1448098 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1448098 INFO  
(zkCallback-2071-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1448105 INFO  
(OverseerStateUpdate-96658352622469125-127.0.0.1:39209_solr-n_0000000000) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (1)
   [junit4]   2> 1448105 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39209_solr
   [junit4]   2> 1448107 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1448107 INFO  
(zkCallback-2071-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1448132 INFO  (jetty-launcher-2061-thread-2) 
[n:127.0.0.1:41375_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/.
   [junit4]   2> 1448134 INFO  (jetty-launcher-2061-thread-1) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/.
   [junit4]   2> 1448136 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448138 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1448139 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1448141 INFO  
(SUITE-CleanupOldIndexTest-seed#[F645C96A274416ED]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1448162 INFO  
(TEST-CleanupOldIndexTest.test-seed#[F645C96A274416ED]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1448304 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
async=483cdd39-466f-447e-add9-209d1586470c&replicationFactor=2&collection.configName=conf1&name=oldindextest&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1448305 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=483cdd39-466f-447e-add9-209d1586470c&replicationFactor=2&collection.configName=conf1&name=oldindextest&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1448308 INFO  
(OverseerThreadFactory-5511-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
oldindextest
   [junit4]   2> 1448312 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1448312 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1448419 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={async=483cdd39-466f-447e-add9-209d1586470c1808584336991872&qt=/admin/cores&collection.configName=conf1&name=oldindextest_shard1_replica2&action=CREATE&numShards=1&collection=oldindextest&shard=shard1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1448419 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
async=483cdd39-466f-447e-add9-209d1586470c1808584336991872&qt=/admin/cores&collection.configName=conf1&name=oldindextest_shard1_replica2&action=CREATE&numShards=1&collection=oldindextest&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1448524 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1448537 INFO  (qtp23694727-11483) [n:127.0.0.1:39209_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={async=483cdd39-466f-447e-add9-209d1586470c1808584336753987&qt=/admin/cores&collection.configName=conf1&name=oldindextest_shard1_replica1&action=CREATE&numShards=1&collection=oldindextest&shard=shard1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1448537 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
async=483cdd39-466f-447e-add9-209d1586470c1808584336753987&qt=/admin/cores&collection.configName=conf1&name=oldindextest_shard1_replica1&action=CREATE&numShards=1&collection=oldindextest&shard=shard1&wt=javabin&version=2
   [junit4]   2> 1448543 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
483cdd39-466f-447e-add9-209d1586470c1808584336991872
   [junit4]   2> 1448543 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=483cdd39-466f-447e-add9-209d1586470c1808584336991872&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1448644 INFO  
(zkCallback-2071-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1448644 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1449317 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1449318 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1449424 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml 
with version 0 
   [junit4]   2> 1449433 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
6.3.0
   [junit4]   2> 1449442 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1449464 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica2] Schema name=cloud-dynamic
   [junit4]   2> 1449497 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica2] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 1449498 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica2] unique key field: id
   [junit4]   2> 1449499 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 
'oldindextest_shard1_replica2' using configuration from collection oldindextest
   [junit4]   2> 1449500 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.SolrCore 
[[oldindextest_shard1_replica2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/oldindextest_shard1_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/./oldindextest_shard1_replica2/data/]
   [junit4]   2> 1449501 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.SolrCore New index directory detected: 
old=null 
new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/./oldindextest_shard1_replica2/data/index/
   [junit4]   2> 1449501 WARN  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.SolrCore [oldindextest_shard1_replica2] 
Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/./oldindextest_shard1_replica2/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1449502 WARN  
(OldIndexDirectoryCleanupThreadForCore-oldindextest_shard1_replica2) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node2/./oldindextest_shard1_replica2/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1449509 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.PluginBag 
[oldindextest_shard1_replica2] Initialized 24 plugins of type requestHandler: 
[/update, /update/json, /update/csv, /update/json/docs, /config, /schema, 
/replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, 
/admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, 
/admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, /select]
   [junit4]   2> 1449510 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/select,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
   [junit4]   2> 1449510 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1449510 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1449511 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1449511 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1449512 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1feb85a[oldindextest_shard1_replica2] main]
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.ManagedResourceStorage Setting up 
ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.RestManager Initializing RestManager 
with initArgs: {}
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.ManagedResourceStorage Reading 
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.ManagedResourceStorage No data found 
for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1449513 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.r.RestManager Initializing 0 registered 
ManagedResources
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1449514 INFO  
(searcherExecutor-5516-thread-1-processing-x:oldindextest_shard1_replica2 
r:core_node1 483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE 
n:127.0.0.1:41375_solr s:shard1 c:oldindextest) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.c.SolrCore [oldindextest_shard1_replica2] Registered new searcher 
Searcher@1feb85a[oldindextest_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.UpdateLog Looking up max value of 
version field to seed version buckets
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.VersionInfo Refreshing highest value of 
_version_ for 65536 version buckets from index
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.VersionInfo No terms found for 
_version_, cannot seed version bucket highest value from index
   [junit4]   2> 1449514 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in 
index or recent updates, using new clock 1546533643645616128
   [junit4]   2> 1449516 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.UpdateLog Took 1.0ms to seed version 
buckets with highest version 1546533643645616128
   [junit4]   2> 1449520 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Waiting 
until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1449544 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml 
with version 0 
   [junit4]   2> 1449548 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
483cdd39-466f-447e-add9-209d1586470c1808584336991872
   [junit4]   2> 1449549 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=483cdd39-466f-447e-add9-209d1586470c1808584336991872&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1449569 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
6.3.0
   [junit4]   2> 1449580 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1449600 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica1] Schema name=cloud-dynamic
   [junit4]   2> 1449622 INFO  
(zkCallback-2071-thread-3-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1449622 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1449643 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica1] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 1449643 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.s.IndexSchema 
[oldindextest_shard1_replica1] unique key field: id
   [junit4]   2> 1449645 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1  
x:oldindextest_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 
'oldindextest_shard1_replica1' using configuration from collection oldindextest
   [junit4]   2> 1449645 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.SolrCore 
[[oldindextest_shard1_replica1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/oldindextest_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/./oldindextest_shard1_replica1/data/]
   [junit4]   2> 1449646 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.SolrCore New index directory detected: 
old=null 
new=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/./oldindextest_shard1_replica1/data/index/
   [junit4]   2> 1449650 WARN  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.SolrCore [oldindextest_shard1_replica1] 
Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/./oldindextest_shard1_replica1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1449651 WARN  
(OldIndexDirectoryCleanupThreadForCore-oldindextest_shard1_replica1) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.DirectoryFactory 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CleanupOldIndexTest_F645C96A274416ED-001/tempDir-001/node1/./oldindextest_shard1_replica1/data/
 does not point to a valid data directory; skipping clean-up of old index 
directories.
   [junit4]   2> 1449658 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.PluginBag 
[oldindextest_shard1_replica1] Initialized 24 plugins of type requestHandler: 
[/update, /update/json, /update/csv, /update/json/docs, /config, /schema, 
/replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system, 
/admin/mbeans, /admin/plugins, /admin/threads, /admin/properties, 
/admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, /select]
   [junit4]   2> 1449658 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/select,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
   [junit4]   2> 1449658 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1449659 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1449659 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1449659 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1449660 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@91e813[oldindextest_shard1_replica1] main]
   [junit4]   2> 1449661 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.ManagedResourceStorage Setting up 
ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 1449661 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1449661 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.RestManager Initializing RestManager 
with initArgs: {}
   [junit4]   2> 1449661 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.ManagedResourceStorage Reading 
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.ManagedResourceStorage No data found 
for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.r.RestManager Initializing 0 registered 
ManagedResources
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1449662 INFO  
(searcherExecutor-5521-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.SolrCore [oldindextest_shard1_replica1] Registered new searcher 
Searcher@91e813[oldindextest_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.UpdateLog Looking up max value of 
version field to seed version buckets
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.VersionInfo Refreshing highest value of 
_version_ for 65536 version buckets from index
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.VersionInfo No terms found for 
_version_, cannot seed version bucket highest value from index
   [junit4]   2> 1449662 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in 
index or recent updates, using new clock 1546533643800805376
   [junit4]   2> 1449665 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.u.UpdateLog Took 2.0ms to seed version 
buckets with highest version 1546533643800805376
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I may be the 
new leader - try and sync
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.PeerSync PeerSync: 
core=oldindextest_shard1_replica2 url=https://127.0.0.1:41375/solr START 
replicas=[https://127.0.0.1:39209/solr/oldindextest_shard1_replica1/] 
nUpdates=100
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.u.PeerSync PeerSync: 
core=oldindextest_shard1_replica2 url=https://127.0.0.1:41375/solr DONE.  We 
have no versions.  sync failed.
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync 
with shard failed, moving to the next candidate
   [junit4]   2> 1450021 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] 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> 1450026 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I am the new 
leader: https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/ shard1
   [junit4]   2> 1450028 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.S.Request [oldindextest_shard1_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1450129 INFO  
(zkCallback-2071-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1450129 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1450178 INFO  
(parallelCoreAdminExecutor-5512-thread-1-processing-n:127.0.0.1:41375_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336991872 CREATE) 
[n:127.0.0.1:41375_solr c:oldindextest s:shard1 r:core_node1 
x:oldindextest_shard1_replica2] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 1450283 INFO  
(zkCallback-2071-thread-3-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1450283 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1450323 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1450324 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1450553 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
483cdd39-466f-447e-add9-209d1586470c1808584336991872
   [junit4]   2> 1450554 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=483cdd39-466f-447e-add9-209d1586470c1808584336991872&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1450558 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
483cdd39-466f-447e-add9-209d1586470c1808584336753987
   [junit4]   2> 1450558 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=483cdd39-466f-447e-add9-209d1586470c1808584336753987&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1450667 INFO  
(parallelCoreAdminExecutor-5514-thread-1-processing-n:127.0.0.1:39209_solr 
483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE) 
[n:127.0.0.1:39209_solr c:oldindextest s:shard1 r:core_node2 
x:oldindextest_shard1_replica1] o.a.s.c.ZkController Core needs to 
recover:oldindextest_shard1_replica1
   [junit4]   2> 1450668 INFO  
(updateExecutor-2065-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.DefaultSolrCoreState Running recovery
   [junit4]   2> 1450668 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 1450668 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
   [junit4]   2> 1450668 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.RecoveryStrategy Begin buffering updates. 
core=[oldindextest_shard1_replica1]
   [junit4]   2> 1450668 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 1450668 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.RecoveryStrategy Publishing state of core 
[oldindextest_shard1_replica1] as recovering, leader is 
[https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/] and I am 
[https://127.0.0.1:39209/solr/oldindextest_shard1_replica1/]
   [junit4]   2> 1450671 INFO  
(recoveryExecutor-2066-thread-1-processing-x:oldindextest_shard1_replica1 
r:core_node2 483cdd39-466f-447e-add9-209d1586470c1808584336753987 CREATE 
n:127.0.0.1:39209_solr s:shard1 c:oldindextest) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.c.RecoveryStrategy Sending prep recovery command to 
[https://127.0.0.1:41375/solr]; [WaitForState: 
action=PREPRECOVERY&core=oldindextest_shard1_replica2&nodeName=127.0.0.1:39209_solr&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 1450675 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
   [junit4]   2> 1450676 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see 
oldindextest_shard1_replica2 (shard1 of oldindextest) have state: recovering
   [junit4]   2> 1450676 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=oldindextest, 
shard=shard1, thisCore=oldindextest_shard1_replica2, 
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, 
currentState=down, localState=active, nodeName=127.0.0.1:39209_solr, 
coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: 
core_node2:{"core":"oldindextest_shard1_replica1","base_url":"https://127.0.0.1:39209/solr","node_name":"127.0.0.1:39209_solr","state":"down"}
   [junit4]   2> 1450772 INFO  
(zkCallback-2071-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1450772 INFO  
(zkCallback-2072-thread-1-processing-n:127.0.0.1:41375_solr) 
[n:127.0.0.1:41375_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> 1451329 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1451329 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1451563 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
483cdd39-466f-447e-add9-209d1586470c1808584336753987
   [junit4]   2> 1451563 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=483cdd39-466f-447e-add9-209d1586470c1808584336753987&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1451566 INFO  
(OverseerThreadFactory-5511-thread-1-processing-n:127.0.0.1:39209_solr) 
[n:127.0.0.1:39209_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> 1451676 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=oldindextest, 
shard=shard1, thisCore=oldindextest_shard1_replica2, 
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, 
currentState=recovering, localState=active, nodeName=127.0.0.1:39209_solr, 
coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: 
core_node2:{"core":"oldindextest_shard1_replica1","base_url":"https://127.0.0.1:39209/solr","node_name":"127.0.0.1:39209_solr","state":"recovering"}
   [junit4]   2> 1451676 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering, 
checkLive: true, onlyIfLeader: true for: 1 seconds.
   [junit4]   2> 1451705 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={nodeName=127.0.0.1:39209_solr&onlyIfLeaderActive=true&core=oldindextest_shard1_replica2&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
 status=0 QTime=1029
   [junit4]   2> 1452311 INFO  
(OverseerCollectionConfigSetProcessor-96658352622469125-127.0.0.1:39209_solr-n_0000000000)
 [n:127.0.0.1:39209_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> 1452334 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1452335 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1452340 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=483cdd39-466f-447e-add9-209d1586470c&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1452340 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=483cdd39-466f-447e-add9-209d1586470c&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1452361 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-0
 (1546533646615183360)]} 0 7
   [junit4]   2> 1452362 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-0 
(1546533646615183360)]} 0 15
   [junit4]   2> 1452371 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-1
 (1546533646637203456)]} 0 0
   [junit4]   2> 1452371 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-1 
(1546533646637203456)]} 0 4
   [junit4]   2> 1452380 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-2
 (1546533646645592064)]} 0 0
   [junit4]   2> 1452380 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-2 
(1546533646645592064)]} 0 5
   [junit4]   2> 1452389 INFO  (qtp23694727-11483) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-3
 (1546533646655029248)]} 0 0
   [junit4]   2> 1452389 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-3 
(1546533646655029248)]} 0 5
   [junit4]   2> 1452396 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-2
 (-1546533646664466432)]} 0 0
   [junit4]   2> 1452397 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-2 
(-1546533646664466432)]} 0 3
   [junit4]   2> 1452406 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-4
 (1546533646673903616)]} 0 0
   [junit4]   2> 1452406 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-4 
(1546533646673903616)]} 0 4
   [junit4]   2> 1452415 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-5
 (1546533646682292224)]} 0 0
   [junit4]   2> 1452416 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-5 
(1546533646682292224)]} 0 5
   [junit4]   2> 1452424 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-5
 (-1546533646692777984)]} 0 0
   [junit4]   2> 1452425 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-5 
(-1546533646692777984)]} 0 4
   [junit4]   2> 1452433 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-6
 (1546533646702215168)]} 0 0
   [junit4]   2> 1452433 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-6 
(1546533646702215168)]} 0 4
   [junit4]   2> 1452459 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-7
 (1546533646711652352)]} 0 0
   [junit4]   2> 1452460 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-7 
(1546533646711652352)]} 0 21
   [junit4]   2> 1452468 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-8
 (1546533646738915328)]} 0 0
   [junit4]   2> 1452468 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-8 
(1546533646738915328)]} 0 4
   [junit4]   2> 1452477 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-9
 (1546533646748352512)]} 0 0
   [junit4]   2> 1452477 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-9 
(1546533646748352512)]} 0 4
   [junit4]   2> 1452486 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-10
 (1546533646756741120)]} 0 0
   [junit4]   2> 1452486 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-10 
(1546533646756741120)]} 0 5
   [junit4]   2> 1452493 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-9
 (-1546533646766178304)]} 0 0
   [junit4]   2> 1452494 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-9 
(-1546533646766178304)]} 0 3
   [junit4]   2> 1452502 INFO  (qtp23694727-11483) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-11
 (1546533646774566912)]} 0 0
   [junit4]   2> 1452502 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-11 
(1546533646774566912)]} 0 4
   [junit4]   2> 1452510 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-12
 (1546533646782955520)]} 0 0
   [junit4]   2> 1452511 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-12 
(1546533646782955520)]} 0 4
   [junit4]   2> 1452521 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-11
 (-1546533646793441280)]} 0 0
   [junit4]   2> 1452521 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-11 
(-1546533646793441280)]} 0 5
   [junit4]   2> 1452531 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-13
 (1546533646803927040)]} 0 0
   [junit4]   2> 1452532 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-13 
(1546533646803927040)]} 0 5
   [junit4]   2> 1452540 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-14
 (1546533646814412800)]} 0 0
   [junit4]   2> 1452541 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-14 
(1546533646814412800)]} 0 4
   [junit4]   2> 1452551 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-12
 (-1546533646825947136)]} 0 0
   [junit4]   2> 1452551 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-12 
(-1546533646825947136)]} 0 4
   [junit4]   2> 1452560 INFO  (qtp23694727-11483) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-15
 (1546533646835384320)]} 0 0
   [junit4]   2> 1452561 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-15 
(1546533646835384320)]} 0 4
   [junit4]   2> 1452570 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-16
 (1546533646844821504)]} 0 0
   [junit4]   2> 1452570 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-16 
(1546533646844821504)]} 0 5
   [junit4]   2> 1452577 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-13
 (-1546533646854258688)]} 0 0
   [junit4]   2> 1452577 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-13 
(-1546533646854258688)]} 0 3
   [junit4]   2> 1452586 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-17
 (1546533646862647296)]} 0 0
   [junit4]   2> 1452586 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-17 
(1546533646862647296)]} 0 4
   [junit4]   2> 1452594 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-18
 (1546533646869987328)]} 0 0
   [junit4]   2> 1452594 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-18 
(1546533646869987328)]} 0 5
   [junit4]   2> 1452603 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-19
 (1546533646879424512)]} 0 0
   [junit4]   2> 1452604 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-19 
(1546533646879424512)]} 0 6
   [junit4]   2> 1452611 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-20
 (1546533646889910272)]} 0 0
   [junit4]   2> 1452612 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-20 
(1546533646889910272)]} 0 3
   [junit4]   2> 1452621 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-21
 (1546533646898298880)]} 0 0
   [junit4]   2> 1452621 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-21 
(1546533646898298880)]} 0 4
   [junit4]   2> 1452631 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-15
 (-1546533646907736064)]} 0 0
   [junit4]   2> 1452632 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-15 
(-1546533646907736064)]} 0 7
   [junit4]   2> 1452641 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-22
 (1546533646919270400)]} 0 0
   [junit4]   2> 1452641 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-22 
(1546533646919270400)]} 0 5
   [junit4]   2> 1452648 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-23
 (1546533646928707584)]} 0 0
   [junit4]   2> 1452649 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-23 
(1546533646928707584)]} 0 3
   [junit4]   2> 1452657 INFO  (qtp23694727-11483) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-24
 (1546533646938144768)]} 0 0
   [junit4]   2> 1452658 INFO  (qtp16727922-11494) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-24 
(1546533646938144768)]} 0 4
   [junit4]   2> 1452666 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-25
 (1546533646946533376)]} 0 0
   [junit4]   2> 1452667 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-25 
(1546533646946533376)]} 0 5
   [junit4]   2> 1452673 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-16
 (-1546533646954921984)]} 0 0
   [junit4]   2> 1452674 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-16 
(-1546533646954921984)]} 0 3
   [junit4]   2> 1452682 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-26
 (1546533646963310592)]} 0 0
   [junit4]   2> 1452682 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-26 
(1546533646963310592)]} 0 4
   [junit4]   2> 1452690 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-27
 (1546533646971699200)]} 0 0
   [junit4]   2> 1452691 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-27 
(1546533646971699200)]} 0 5
   [junit4]   2> 1452697 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-18
 (-1546533646980087808)]} 0 0
   [junit4]   2> 1452697 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-18 
(-1546533646980087808)]} 0 3
   [junit4]   2> 1452705 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-28
 (1546533646988476416)]} 0 0
   [junit4]   2> 1452706 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-28 
(1546533646988476416)]} 0 3
   [junit4]   2> 1452713 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-19
 (-1546533646995816448)]} 0 0
   [junit4]   2> 1452713 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-19 
(-1546533646995816448)]} 0 4
   [junit4]   2> 1452721 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-29
 (1546533647004205056)]} 0 0
   [junit4]   2> 1452721 INFO  (qtp16727922-11484) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-29 
(1546533647004205056)]} 0 4
   [junit4]   2> 1452736 INFO  (qtp23694727-11497) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-20
 (-1546533647019933696)]} 0 1
   [junit4]   2> 1452737 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-20 
(-1546533647019933696)]} 0 4
   [junit4]   2> 1452744 INFO  (qtp23694727-11493) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-30
 (1546533647028322304)]} 0 0
   [junit4]   2> 1452744 INFO  (qtp16727922-11488) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-30 
(1546533647028322304)]} 0 4
   [junit4]   2> 1452753 INFO  (qtp23694727-11495) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-31
 (1546533647037759488)]} 0 0
   [junit4]   2> 1452753 INFO  (qtp16727922-11486) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-31 
(1546533647037759488)]} 0 4
   [junit4]   2> 1452762 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-32
 (1546533647046148096)]} 0 0
   [junit4]   2> 1452762 INFO  (qtp16727922-11496) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-32 
(1546533647046148096)]} 0 5
   [junit4]   2> 1452770 INFO  (qtp23694727-11485) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{delete=[1-21
 (-1546533647055585280)]} 0 0
   [junit4]   2> 1452770 INFO  (qtp16727922-11482) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-21 
(-1546533647055585280)]} 0 3
   [junit4]   2> 1452779 INFO  (qtp23694727-11487) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r:core_node2 x:oldindextest_shard1_replica1] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:41375/solr/oldindextest_shard1_replica2/&wt=javabin&version=2}{add=[1-33
 (1546533647065022464)]} 0 0
   [junit4]   2> 1452780 INFO  (qtp16727922-11492) [n:127.0.0.1:41375_solr 
c:oldindextest s:shard1 r:core_node1 x:oldindextest_shard1_replica2] 
o.a.s.u.p.LogUpdateProcessorFactory [oldindextest_shard1_replica2]  
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-33 
(1546533647065022464)]} 0 4
   [junit4]   2> 1452788 INFO  (qtp23694727-11489) [n:127.0.0.1:39209_solr 
c:oldindextest s:shard1 r

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

>       at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2>        at 
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
   [junit4]   2>        at 
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:289)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Sep 26, 2016 1:30:36 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=11559, name=CloudSolrClient 
ThreadPool-2076-thread-1-processing-https:////127.0.0.1:41375//solr//oldindextest_shard1_replica2//,
 state=RUNNABLE, group=TGRP-CleanupOldIndexTest]
   [junit4]   2>         at java.net.SocketInputStream.socketRead0(Native 
Method)
   [junit4]   2>         at 
java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
   [junit4]   2>         at 
java.net.SocketInputStream.read(SocketInputStream.java:170)
   [junit4]   2>         at 
java.net.SocketInputStream.read(SocketInputStream.java:141)
   [junit4]   2>         at 
sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
   [junit4]   2>         at 
sun.security.ssl.InputRecord.read(InputRecord.java:503)
   [junit4]   2>         at 
sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973)
   [junit4]   2>         at 
sun.security.ssl.SSLSocketImpl.performInitialHandshake(SSLSocketImpl.java:1375)
   [junit4]   2>         at 
sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1403)
   [junit4]   2>         at 
sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1387)
   [junit4]   2>         at 
org.apache.http.conn.ssl.SSLSocketFactory.connectSocket(SSLSocketFactory.java:543)
   [junit4]   2>         at 
org.apache.http.conn.ssl.SSLSocketFactory.connectSocket(SSLSocketFactory.java:409)
   [junit4]   2>         at 
org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:177)
   [junit4]   2>         at 
org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:304)
   [junit4]   2>         at 
org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:611)
   [junit4]   2>         at 
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:446)
   [junit4]   2>         at 
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
   [junit4]   2>         at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
   [junit4]   2>         at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
   [junit4]   2>         at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:497)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:405)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:730)
   [junit4]   2>         at 
org.apache.solr.client.solrj.impl.CloudSolrClient$$Lambda$165/1936344.call(Unknown
 Source)
   [junit4]   2>         at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>         at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>         at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$25/14662394.run(Unknown
 Source)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{rnd_b=Lucene50(blocksize=128), 
_version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_i=Lucene50(blocksize=128), id=Lucene50(blocksize=128)}, docValues:{}, 
maxPointsInLeafNode=311, maxMBSortInHeap=5.127909002511744, 
sim=ClassicSimilarity, locale=ko, timezone=Atlantic/Bermuda
   [junit4]   2> NOTE: Linux 4.4.0-36-generic i386/Oracle Corporation 1.8.0_102 
(32-bit)/cpus=12,threads=2,free=123647400,total=375656448
   [junit4]   2> NOTE: All tests run in this JVM: [PeerSyncTest, 
HdfsChaosMonkeyNothingIsSafeTest, TolerantUpdateProcessorTest, 
ClusterStateTest, CloudMLTQParserTest, 
OverseerCollectionConfigSetProcessorTest, 
HdfsTlogReplayBufferedWhileIndexingTest, HdfsLockFactoryTest, 
TestExceedMaxTermLength, TestStressLucene, AsyncCallRequestStatusResponseTest, 
BasicDistributedZkTest, TestManagedResourceStorage, TestIntervalFaceting, 
TestCollationFieldDocValues, PathHierarchyTokenizerFactoryTest, 
SharedFSAutoReplicaFailoverTest, SyncSliceTest, XsltUpdateRequestHandlerTest, 
DeleteInactiveReplicaTest, DistributedQueryElevationComponentTest, 
ExternalFileFieldSortTest, TestSolrConfigHandlerCloud, 
FastVectorHighlighterTest, TestTestInjection, DateFieldTest, 
TestBinaryResponseWriter, TestFieldResource, TestRawResponseWriter, 
TestSimpleQParserPlugin, TestRealTimeGet, TestRemoteStreaming, 
TestValueSourceCache, TestRebalanceLeaders, DistributedSpellCheckComponentTest, 
BasicZkTest, TestCSVLoader, TestNRTOpen, ZkStateWriterTest, 
AnalysisAfterCoreReloadTest, CollectionStateFormat2Test, ConnectionManagerTest, 
TestRangeQuery, OverseerStatusTest, EnumFieldTest, 
TestOnReconnectListenerSupport, SolrCLIZkUtilsTest, SolrCoreTest, 
TestSolrFieldCacheMBean, CdcrUpdateLogTest, OutputWriterTest, 
RequiredFieldsTest, HttpPartitionTest, TestComponentsName, ScriptEngineTest, 
UUIDFieldTest, TestDistributedSearch, TestPerFieldSimilarity, 
TestRequestStatusCollectionAPI, BlockDirectoryTest, StandardRequestHandlerTest, 
TestCloudPivotFacet, DocumentAnalysisRequestHandlerTest, TestDocSet, 
TestManagedSynonymFilterFactory, SolrRequestParserTest, 
TestDFISimilarityFactory, ReplicationFactorTest, TestQuerySenderNoQuery, 
ExitableDirectoryReaderTest, SignatureUpdateProcessorFactoryTest, 
TestSolrCoreProperties, TestCloudManagedSchema, TestSchemaVersionResource, 
TestReplicationHandler, TestAuthenticationFramework, 
TestTolerantUpdateProcessorRandomCloud, TestHighlightDedupGrouping, 
DistributedFacetPivotLargeTest, TestSizeLimitedDistributedMap, 
CachingDirectoryFactoryTest, TestFastWriter, TestFilteredDocIdSet, 
TestFaceting, TestJsonFacets, TestOmitPositions, TestCodecSupport, 
ChaosMonkeyNothingIsSafeTest, TestFastLRUCache, TestReqParamsAPI, 
CursorPagingTest, DistributedDebugComponentTest, TestRandomDVFaceting, 
TestPseudoReturnFields, TestSolrQueryParserResource, TestConfigSetsAPI, 
CacheHeaderTest, DistribCursorPagingTest, RegexBoostProcessorTest, 
TestLegacyFieldCache, TestFieldCacheReopen, TestInitQParser, TestZkChroot, 
TestHashPartitioner, TestJoin, StatsComponentTest, TestFiltering, 
CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, TestExtendedDismaxParser, 
SpellCheckCollatorTest, TestFoldingMultitermQuery, DocValuesTest, 
DistanceFunctionTest, SolrInfoMBeanTest, LukeRequestHandlerTest, 
FileBasedSpellCheckerTest, DocumentBuilderTest, 
FieldAnalysisRequestHandlerTest, TestJmxIntegration, 
UpdateRequestProcessorFactoryTest, MBeansHandlerTest, 
UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, 
CSVRequestHandlerTest, HighlighterConfigTest, SOLR749Test, 
TestQuerySenderListener, AlternateDirectoryTest, TestStressRecovery, 
TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, TestSearchPerf, 
NumericFieldsTest, TestElisionMultitermQuery, TestConfig, 
TestPostingsSolrHighlighter, DirectSolrConnectionTest, 
NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCharFilters, 
SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, 
TestSweetSpotSimilarityFactory, TestLMJelinekMercerSimilarityFactory, 
TestIBSimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, 
TestFastOutputStream, OpenExchangeRatesOrgProviderTest, 
URLClassifyProcessorTest, PluginInfoTest, DateMathParserTest, 
PreAnalyzedFieldTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, 
TestSolrJ, TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, 
SystemInfoHandlerTest, CircularListTest, DistributedMLTComponentTest, 
TestRTGBase, TestCrossCoreJoin, TestDistributedMissingSort, TestTolerantSearch, 
ConnectionReuseTest, TestJettySolrRunner, ActionThrottleTest, 
AliasIntegrationTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, 
CleanupOldIndexTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=CleanupOldIndexTest 
-Dtests.seed=F645C96A274416ED -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ko -Dtests.timezone=Atlantic/Bermuda -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | CleanupOldIndexTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 
7200000 msec).
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([F645C96A274416ED]:0)
   [junit4] Completed [635/635 (1!)] on J0 in 7223.23s, 1 test, 2 errors <<< 
FAILURES!

[...truncated 52620 lines...]


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org