Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1672/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

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

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.TestUtilizeNode:     
1) Thread[id=9482, name=qtp270146496-9482, state=TIMED_WAITING, 
group=TGRP-TestUtilizeNode]         at sun.misc.Unsafe.park(Native Method)      
   at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)    
     at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.cloud.TestUtilizeNode: 
   1) Thread[id=9482, name=qtp270146496-9482, state=TIMED_WAITING, 
group=TGRP-TestUtilizeNode]
        at sun.misc.Unsafe.park(Native Method)
        at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([1393656331314A8C]:0)


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

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=9482, name=qtp270146496-9482, state=TIMED_WAITING, 
group=TGRP-TestUtilizeNode]         at sun.misc.Unsafe.park(Native Method)      
   at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)    
     at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=9482, name=qtp270146496-9482, state=TIMED_WAITING, 
group=TGRP-TestUtilizeNode]
        at sun.misc.Unsafe.park(Native Method)
        at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([1393656331314A8C]:0)




Build Log:
[...truncated 12664 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestUtilizeNode
   [junit4]   2> 1253285 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/init-core-data-001
   [junit4]   2> 1253285 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1253287 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1253287 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001
   [junit4]   2> 1253287 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1253287 INFO  (Thread-2132) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1253287 INFO  (Thread-2132) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1253289 ERROR (Thread-2132) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1253392 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:33341
   [junit4]   2> 1253394 INFO  (zkConnectionManagerCallback-2059-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253399 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1253399 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1253399 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1253400 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T04:27:37+07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1253400 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1253400 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1253400 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1253401 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1253401 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1253401 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 1253402 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1253402 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1253402 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1253403 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1253403 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1253403 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 1253403 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@29c1ac54{/solr,null,AVAILABLE}
   [junit4]   2> 1253404 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@20b0c291{/solr,null,AVAILABLE}
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@8cc2d46{HTTP/1.1,[http/1.1]}{127.0.0.1:64290}
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.e.j.s.Server Started @1257084ms
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64290}
   [junit4]   2> 1253405 ERROR (jetty-launcher-2056-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@28c4480a{HTTP/1.1,[http/1.1]}{127.0.0.1:56212}
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.e.j.s.Server Started @1257085ms
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=56212}
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-11T15:42:01.350Z
   [junit4]   2> 1253405 ERROR (jetty-launcher-2056-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1253405 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1253406 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-11T15:42:01.351Z
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5cfc921d{/solr,null,AVAILABLE}
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@408ea37e{/solr,null,AVAILABLE}
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@8f0c65d{HTTP/1.1,[http/1.1]}{127.0.0.1:65057}
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.e.j.s.Server Started @1257086ms
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=65057}
   [junit4]   2> 1253407 ERROR (jetty-launcher-2056-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1253407 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-11T15:42:01.352Z
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@711d8e53{HTTP/1.1,[http/1.1]}{127.0.0.1:48165}
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.e.j.s.Server Started @1257087ms
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=48165}
   [junit4]   2> 1253408 ERROR (jetty-launcher-2056-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1253408 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-11T15:42:01.353Z
   [junit4]   2> 1253409 INFO  (zkConnectionManagerCallback-2061-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253409 INFO  (zkConnectionManagerCallback-2063-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253410 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1253410 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1253411 INFO  (zkConnectionManagerCallback-2065-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253411 INFO  (zkConnectionManagerCallback-2067-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253412 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1253418 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1253420 INFO  (jetty-launcher-2056-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33341/solr
   [junit4]   2> 1253421 INFO  (jetty-launcher-2056-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33341/solr
   [junit4]   2> 1253423 INFO  (zkConnectionManagerCallback-2072-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253424 INFO  (zkConnectionManagerCallback-2075-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253426 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x100700e9ebf0006, likely client has closed socket
   [junit4]   2> 1253427 INFO  
(zkConnectionManagerCallback-2077-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253427 INFO  (jetty-launcher-2056-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33341/solr
   [junit4]   2> 1253429 INFO  
(zkConnectionManagerCallback-2080-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253430 INFO  (jetty-launcher-2056-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33341/solr
   [junit4]   2> 1253430 INFO  (zkConnectionManagerCallback-2084-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253433 INFO  (zkConnectionManagerCallback-2087-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253435 INFO  
(zkConnectionManagerCallback-2089-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253438 INFO  
(zkConnectionManagerCallback-2091-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1253653 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1253654 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1253655 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:65057_solr
   [junit4]   2> 1253656 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:56212_solr
   [junit4]   2> 1253658 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.Overseer Overseer 
(id=72180802133098508-127.0.0.1:65057_solr-n_0000000000) starting
   [junit4]   2> 1253659 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1253661 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1253664 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1253668 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1253674 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:48165_solr
   [junit4]   2> 1253676 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:65057_solr
   [junit4]   2> 1253677 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1253677 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1253678 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1253684 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1253684 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1253684 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1253795 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1253798 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1253800 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64290_solr
   [junit4]   2> 1253808 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1253808 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1253809 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1253822 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1253886 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64290.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253901 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64290.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253901 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64290.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253902 INFO  (jetty-launcher-2056-thread-3) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node3/.
   [junit4]   2> 1253915 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_48165.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253930 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_48165.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253930 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_48165.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253931 INFO  (jetty-launcher-2056-thread-4) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node4/.
   [junit4]   2> 1253936 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65057.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253946 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65057.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253946 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65057.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253947 INFO  (jetty-launcher-2056-thread-2) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node2/.
   [junit4]   2> 1253966 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56212.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253972 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56212.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253972 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56212.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1253973 INFO  (jetty-launcher-2056-thread-1) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node1/.
   [junit4]   2> 1254021 INFO  (zkConnectionManagerCallback-2101-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1254025 INFO  (zkConnectionManagerCallback-2105-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1254027 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1254029 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33341/solr ready
   [junit4]   2> 1254038 INFO  (qtp1416522868-9294) [n:127.0.0.1:64290_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1254049 INFO  (qtp1416522868-9294) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=10
   [junit4]   2> 1254086 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.a.s.SolrTestCaseJ4 
###Starting test
   [junit4]   2> 1254086 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1254093 INFO  (zkConnectionManagerCallback-2108-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1254096 INFO  (qtp1416522868-9295) [n:127.0.0.1:64290_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf1&name=utilizenodecoll&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1254100 INFO  
(OverseerThreadFactory-3188-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
utilizenodecoll
   [junit4]   2> 1254100 INFO  
(OverseerCollectionConfigSetProcessor-72180802133098508-127.0.0.1:65057_solr-n_0000000000)
 [n:127.0.0.1:65057_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> 1254224 INFO  
(OverseerStateUpdate-72180802133098508-127.0.0.1:65057_solr-n_0000000000) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"utilizenodecoll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"utilizenodecoll_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:64290/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1254226 INFO  
(OverseerStateUpdate-72180802133098508-127.0.0.1:65057_solr-n_0000000000) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"utilizenodecoll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"utilizenodecoll_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:56212/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1254229 INFO  
(OverseerStateUpdate-72180802133098508-127.0.0.1:65057_solr-n_0000000000) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"utilizenodecoll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"utilizenodecoll_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:65057/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1254233 INFO  
(OverseerStateUpdate-72180802133098508-127.0.0.1:65057_solr-n_0000000000) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"utilizenodecoll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"utilizenodecoll_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:48165/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1254441 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n1&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1254441 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n4&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1254441 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1254441 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1254445 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n2&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1254446 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1254449 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n6&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1254449 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1254562 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254562 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254562 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254562 INFO  
(zkCallback-2090-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254563 INFO  
(zkCallback-2079-thread-2-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254562 INFO  
(zkCallback-2076-thread-2-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254563 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1254563 INFO  
(zkCallback-2088-thread-2-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1255483 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1255484 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1255492 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1255500 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1255513 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.s.IndexSchema [utilizenodecoll_shard1_replica_n1] Schema 
name=cloud-dynamic
   [junit4]   2> 1255514 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.s.IndexSchema [utilizenodecoll_shard2_replica_n6] Schema 
name=cloud-dynamic
   [junit4]   2> 1255520 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.s.IndexSchema [utilizenodecoll_shard1_replica_n2] Schema 
name=cloud-dynamic
   [junit4]   2> 1255533 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.s.IndexSchema [utilizenodecoll_shard2_replica_n4] Schema 
name=cloud-dynamic
   [junit4]   2> 1255611 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1255611 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard2_replica_n4' 
using configuration from collection utilizenodecoll, trusted=true
   [junit4]   2> 1255611 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65057.solr.core.utilizenodecoll.shard2.replica_n4' (registry 
'solr.core.utilizenodecoll.shard2.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1255611 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1255611 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.SolrCore [[utilizenodecoll_shard2_replica_n4] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node2/utilizenodecoll_shard2_replica_n4],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node2/./utilizenodecoll_shard2_replica_n4/data/]
   [junit4]   2> 1255616 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1255616 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard1_replica_n2' 
using configuration from collection utilizenodecoll, trusted=true
   [junit4]   2> 1255616 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_56212.solr.core.utilizenodecoll.shard1.replica_n2' (registry 
'solr.core.utilizenodecoll.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1255616 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1255616 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.SolrCore [[utilizenodecoll_shard1_replica_n2] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node1/utilizenodecoll_shard1_replica_n2],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node1/./utilizenodecoll_shard1_replica_n2/data/]
   [junit4]   2> 1255628 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1255628 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard2_replica_n6' 
using configuration from collection utilizenodecoll, trusted=true
   [junit4]   2> 1255629 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_48165.solr.core.utilizenodecoll.shard2.replica_n6' (registry 
'solr.core.utilizenodecoll.shard2.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1255629 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1255629 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.c.SolrCore [[utilizenodecoll_shard2_replica_n6] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node4/utilizenodecoll_shard2_replica_n6],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node4/./utilizenodecoll_shard2_replica_n6/data/]
   [junit4]   2> 1255640 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1255640 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard1_replica_n1' 
using configuration from collection utilizenodecoll, trusted=true
   [junit4]   2> 1255641 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_64290.solr.core.utilizenodecoll.shard1.replica_n1' (registry 
'solr.core.utilizenodecoll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1255641 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1255641 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.c.SolrCore [[utilizenodecoll_shard1_replica_n1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node3/utilizenodecoll_shard1_replica_n1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node3/./utilizenodecoll_shard1_replica_n1/data/]
   [junit4]   2> 1255937 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1255937 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1255937 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1255937 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1255938 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1255939 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1255938 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1255939 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1255940 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1255941 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1255941 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7b32371b[utilizenodecoll_shard2_replica_n4] main]
   [junit4]   2> 1255942 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1255942 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1255942 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1255943 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1255944 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4bd379d7[utilizenodecoll_shard1_replica_n1] main]
   [junit4]   2> 1255944 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1255944 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592119760140632064
   [junit4]   2> 1255946 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1255946 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1255947 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1255947 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1255949 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6b13abeb[utilizenodecoll_shard1_replica_n2] main]
   [junit4]   2> 1255950 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@726cbb73[utilizenodecoll_shard2_replica_n6] main]
   [junit4]   2> 1255951 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1255952 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1255952 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1255953 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1255953 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1255954 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592119760151117824
   [junit4]   2> 1255955 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1255956 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592119760153214976
   [junit4]   2> 1255968 INFO  
(searcherExecutor-3197-thread-1-processing-n:127.0.0.1:65057_solr 
x:utilizenodecoll_shard2_replica_n4 s:shard2 c:utilizenodecoll r:core_node7) 
[n:127.0.0.1:65057_solr c:utilizenodecoll s:shard2 r:core_node7 
x:utilizenodecoll_shard2_replica_n4] o.a.s.c.SolrCore 
[utilizenodecoll_shard2_replica_n4] Registered new searcher 
Searcher@7b32371b[utilizenodecoll_shard2_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1255968 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1255969 INFO  
(searcherExecutor-3198-thread-1-processing-n:127.0.0.1:56212_solr 
x:utilizenodecoll_shard1_replica_n2 s:shard1 c:utilizenodecoll r:core_node5) 
[n:127.0.0.1:56212_solr c:utilizenodecoll s:shard1 r:core_node5 
x:utilizenodecoll_shard1_replica_n2] o.a.s.c.SolrCore 
[utilizenodecoll_shard1_replica_n2] Registered new searcher 
Searcher@6b13abeb[utilizenodecoll_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1255970 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1255971 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 1255971 INFO  
(searcherExecutor-3200-thread-1-processing-n:127.0.0.1:64290_solr 
x:utilizenodecoll_shard1_replica_n1 s:shard1 c:utilizenodecoll r:core_node3) 
[n:127.0.0.1:64290_solr c:utilizenodecoll s:shard1 r:core_node3 
x:utilizenodecoll_shard1_replica_n1] o.a.s.c.SolrCore 
[utilizenodecoll_shard1_replica_n1] Registered new searcher 
Searcher@4bd379d7[utilizenodecoll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1255971 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1255971 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1255976 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592119760174186496
   [junit4]   2> 1255977 INFO  
(searcherExecutor-3199-thread-1-processing-n:127.0.0.1:48165_solr 
x:utilizenodecoll_shard2_replica_n6 s:shard2 c:utilizenodecoll r:core_node8) 
[n:127.0.0.1:48165_solr c:utilizenodecoll s:shard2 r:core_node8 
x:utilizenodecoll_shard2_replica_n6] o.a.s.c.SolrCore 
[utilizenodecoll_shard2_replica_n6] Registered new searcher 
Searcher@726cbb73[utilizenodecoll_shard2_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1256073 INFO  
(zkCallback-2076-thread-2-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256073 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256073 INFO  
(zkCallback-2079-thread-2-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256073 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256073 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256073 INFO  
(zkCallback-2090-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256074 INFO  
(zkCallback-2088-thread-2-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256074 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256472 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1256472 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1256472 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:56212/solr/utilizenodecoll_shard1_replica_n2/
   [junit4]   2> 1256473 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard1_replica_n2 
url=http://127.0.0.1:56212/solr START 
replicas=[http://127.0.0.1:64290/solr/utilizenodecoll_shard1_replica_n1/] 
nUpdates=100
   [junit4]   2> 1256472 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1256474 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1256474 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:65057/solr/utilizenodecoll_shard2_replica_n4/
   [junit4]   2> 1256475 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard2_replica_n4 
url=http://127.0.0.1:65057/solr START 
replicas=[http://127.0.0.1:48165/solr/utilizenodecoll_shard2_replica_n6/] 
nUpdates=100
   [junit4]   2> 1256478 INFO  (qtp1416522868-9296) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.c.S.Request [utilizenodecoll_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1256480 INFO  (qtp347164769-9315) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.c.S.Request [utilizenodecoll_shard2_replica_n6]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1256775 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard1_replica_n2 
url=http://127.0.0.1:56212/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1256775 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1256775 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1256775 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1256776 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.u.PeerSync PeerSync: core=utilizenodecoll_shard2_replica_n4 
url=http://127.0.0.1:65057/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1256777 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1256777 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
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> 1256777 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1256784 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:56212/solr/utilizenodecoll_shard1_replica_n2/ shard1
   [junit4]   2> 1256784 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:65057/solr/utilizenodecoll_shard2_replica_n4/ shard2
   [junit4]   2> 1256896 INFO  
(zkCallback-2088-thread-2-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256896 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256896 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256896 INFO  
(zkCallback-2079-thread-2-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256897 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256897 INFO  
(zkCallback-2090-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256897 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256897 INFO  
(zkCallback-2076-thread-2-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1256936 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1256936 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1256942 INFO  (qtp1268173969-9292) [n:127.0.0.1:65057_solr 
c:utilizenodecoll s:shard2 r:core_node7 x:utilizenodecoll_shard2_replica_n4] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n4&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2502
   [junit4]   2> 1256943 INFO  (qtp293205333-9302) [n:127.0.0.1:56212_solr 
c:utilizenodecoll s:shard1 r:core_node5 x:utilizenodecoll_shard1_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n2&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2498
   [junit4]   2> 1256975 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr 
c:utilizenodecoll s:shard1 r:core_node3 x:utilizenodecoll_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard1_replica_n1&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2535
   [junit4]   2> 1256992 INFO  (qtp347164769-9311) [n:127.0.0.1:48165_solr 
c:utilizenodecoll s:shard2 r:core_node8 x:utilizenodecoll_shard2_replica_n6] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=utilizenodecoll_shard2_replica_n6&action=CREATE&numShards=2&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2544
   [junit4]   2> 1257000 INFO  (qtp1416522868-9295) [n:127.0.0.1:64290_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 1257098 INFO  
(zkCallback-2079-thread-2-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257098 INFO  
(zkCallback-2088-thread-2-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257098 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257099 INFO  
(zkCallback-2076-thread-2-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257099 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257099 INFO  
(zkCallback-2090-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257099 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1257098 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1258001 INFO  (qtp1416522868-9295) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf1&name=utilizenodecoll&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=3906
   [junit4]   2> 1258004 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T04:27:37+07:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1258005 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.e.j.s.session 
DefaultSessionIdManager workerName=node0
   [junit4]   2> 1258005 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.e.j.s.session No 
SessionScavenger set, using defaults
   [junit4]   2> 1258005 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.e.j.s.session 
Scavenging every 660000ms
   [junit4]   2> 1258006 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@700f8831{/solr,null,AVAILABLE}
   [junit4]   2> 1258007 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1bbc684{HTTP/1.1,[http/1.1]}{127.0.0.1:65008}
   [junit4]   2> 1258007 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.e.j.s.Server 
Started @1261688ms
   [junit4]   2> 1258007 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=65008}
   [junit4]   2> 1258007 ERROR 
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1258025 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1258025 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1258025 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1258025 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-11T15:42:05.970Z
   [junit4]   2> 1258028 INFO  (zkConnectionManagerCallback-2110-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1258029 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1258097 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [    ] o.a.s.c.ZkContainer 
Zookeeper client=127.0.0.1:33341/solr
   [junit4]   2> 1258099 INFO  (zkConnectionManagerCallback-2114-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1258104 INFO  
(zkConnectionManagerCallback-2116-thread-1-processing-n:127.0.0.1:65008_solr) 
[n:127.0.0.1:65008_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1258106 INFO  
(OverseerCollectionConfigSetProcessor-72180802133098508-127.0.0.1:65057_solr-n_0000000000)
 [n:127.0.0.1:65057_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1258120 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1258132 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1258137 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:65008_solr
   [junit4]   2> 1258139 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 1258139 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 1258140 INFO  (zkCallback-2104-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1258140 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 1258140 INFO  (zkCallback-2104-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1258140 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 1258141 INFO  
(zkCallback-2115-thread-1-processing-n:127.0.0.1:65008_solr) 
[n:127.0.0.1:65008_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 1258309 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65008.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1258318 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65008.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1258318 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65008.solr.jetty' 
(registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1258320 INFO  
(TEST-TestUtilizeNode.test-seed#[1393656331314A8C]) [n:127.0.0.1:65008_solr    
] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node5/.
   [junit4]   2> 1258484 INFO  (zkConnectionManagerCallback-2120-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1258488 INFO  (qtp347164769-9315) [n:127.0.0.1:48165_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :utilizenode with params 
node=127.0.0.1:65008_solr&action=UTILIZENODE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1258503 INFO  (qtp347164769-9316) [n:127.0.0.1:48165_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n6:INDEX.sizeInBytes}
 status=0 QTime=3
   [junit4]   2> 1258505 INFO  (qtp347164769-9313) [n:127.0.0.1:48165_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258511 INFO  (qtp293205333-9306) [n:127.0.0.1:56212_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 1258512 INFO  (qtp293205333-9304) [n:127.0.0.1:56212_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258514 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258515 INFO  (qtp1416522868-9300) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258520 INFO  (qtp1558180243-9438) [n:127.0.0.1:65008_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 1258524 INFO  (qtp1268173969-9321) [n:127.0.0.1:65057_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258526 INFO  (qtp1268173969-9319) [n:127.0.0.1:65057_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258528 INFO  (qtp347164769-9316) [n:127.0.0.1:48165_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n6:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258529 INFO  (qtp347164769-9313) [n:127.0.0.1:48165_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258530 INFO  (qtp293205333-9306) [n:127.0.0.1:56212_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258532 INFO  (qtp293205333-9304) [n:127.0.0.1:56212_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258533 INFO  (qtp1416522868-9301) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258534 INFO  (qtp1416522868-9300) [n:127.0.0.1:64290_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258535 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258537 INFO  (qtp1268173969-9323) [n:127.0.0.1:65057_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.utilizenodecoll.shard2.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1258538 INFO  (qtp1268173969-9319) [n:127.0.0.1:65057_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1258540 INFO  
(OverseerThreadFactory-3188-thread-3-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 1880011588 sessionWrapper.createTime 1880011587736734, 
this.sessionWrapper.createTime 1880011587736734 
   [junit4]   2> 1258540 INFO  
(OverseerThreadFactory-3188-thread-3-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.a.c.MoveReplicaCmd Replica will be moved 
to node 127.0.0.1:65008_solr: 
core_node8:{"core":"utilizenodecoll_shard2_replica_n6","base_url":"http://127.0.0.1:48165/solr","node_name":"127.0.0.1:48165_solr","state":"active","type":"NRT"}
   [junit4]   2> 1258542 INFO  
(OverseerThreadFactory-3188-thread-3-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.a.c.AddReplicaCmd Node Identified 
127.0.0.1:65008_solr for creating new replica
   [junit4]   2> 1258548 INFO  
(OverseerStateUpdate-72180802133098508-127.0.0.1:65057_solr-n_0000000000) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"addreplica",
   [junit4]   2>   "collection":"utilizenodecoll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"utilizenodecoll_shard2_replica_n9",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:65008/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:65008_solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 1258650 INFO  
(zkCallback-2076-thread-1-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258650 INFO  
(zkCallback-2076-thread-2-processing-n:127.0.0.1:56212_solr) 
[n:127.0.0.1:56212_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258651 INFO  
(zkCallback-2090-thread-1-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258652 INFO  
(zkCallback-2090-thread-2-processing-n:127.0.0.1:65057_solr) 
[n:127.0.0.1:65057_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258653 INFO  
(zkCallback-2079-thread-2-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258653 INFO  
(zkCallback-2088-thread-2-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258653 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258655 INFO  
(zkCallback-2079-thread-1-processing-n:127.0.0.1:64290_solr) 
[n:127.0.0.1:64290_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258763 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf1&name=utilizenodecoll_shard2_replica_n9&action=CREATE&collection=utilizenodecoll&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1258764 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1258779 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1258807 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.s.IndexSchema [utilizenodecoll_shard2_replica_n9] Schema 
name=cloud-dynamic
   [junit4]   2> 1258853 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 1258853 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.c.CoreContainer Creating SolrCore 'utilizenodecoll_shard2_replica_n9' 
using configuration from collection utilizenodecoll, trusted=true
   [junit4]   2> 1258854 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65008.solr.core.utilizenodecoll.shard2.replica_n9' (registry 
'solr.core.utilizenodecoll.shard2.replica_n9') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5d1589a7
   [junit4]   2> 1258854 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1258854 INFO  (qtp1558180243-9437) [n:127.0.0.1:65008_solr 
c:utilizenodecoll s:shard2 r:core_node10 x:utilizenodecoll_shard2_replica_n9] 
o.a.s.c.SolrCore [[utilizenodecoll_shard2_replica_n9] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node5/utilizenodecoll_shard2_replica_n9],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.TestUtilizeNode_1393656331314A8C-001/tempDir-001/node5/./utilizenodecoll_shard2_replica_n9/data/]
   [junit4]   2> 1258869 INFO  
(zkCallback-2088-thread-1-processing-n:127.0.0.1:48165_solr) 
[n:127.0.0.1:48165_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/utilizenodecoll/state.json] for collection [utilizenodecoll] 
has occurred - updating... (live nodes size: [5])
   [junit4]   2> 1258869 INFO  (zkCallback-2115-thread-2-proces

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

  2>    17      /solr/collections/utilizenodecoll/terms/shard2
   [junit4]   2>        8       /solr/aliases.json
   [junit4]   2>        8       /solr/clusterprops.json
   [junit4]   2>        6       /solr/security.json
   [junit4]   2>        6       /solr/configs/conf1
   [junit4]   2>        5       /solr/collections/utilizenodecoll/terms/shard1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        61      /solr/collections/utilizenodecoll/state.json
   [junit4]   2>        8       /solr/clusterstate.json
   [junit4]   2>        3       
/solr/collections/utilizenodecoll/leader_elect/shard2/election/72180802133098508-core_node7-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/72180802133098503-127.0.0.1:56212_solr-n_0000000001
   [junit4]   2>        2       
/solr/overseer_elect/election/72180802133098504-127.0.0.1:64290_solr-n_0000000003
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        10      /solr/live_nodes
   [junit4]   2>        9       /solr/collections
   [junit4]   2> 
   [junit4]   2> 1296753 INFO  
(SUITE-TestUtilizeNode-seed#[1393656331314A8C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Feb 11, 2018 3:42:44 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> Feb 11, 2018 3:43:04 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.cloud.TestUtilizeNode: 
   [junit4]   2>    1) Thread[id=9482, name=qtp270146496-9482, 
state=TIMED_WAITING, group=TGRP-TestUtilizeNode]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Feb 11, 2018 3:43:04 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=9482, name=qtp270146496-9482, 
state=TIMED_WAITING, group=TGRP-TestUtilizeNode]
   [junit4]   2> Feb 11, 2018 3:43:07 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=9482, name=qtp270146496-9482, 
state=TIMED_WAITING, group=TGRP-TestUtilizeNode]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=623, maxMBSortInHeap=6.19293846178763, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6e59229c),
 locale=es-NI, timezone=America/Indianapolis
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 
(64-bit)/cpus=3,threads=2,free=103527064,total=235106304
   [junit4]   2> NOTE: All tests run in this JVM: [ZkCLITest, 
TestOmitPositions, HLLSerializationTest, TriLevelCompositeIdRoutingTest, 
SampleTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
DistributedQueryElevationComponentTest, TestBinaryField, 
FieldMutatingUpdateProcessorTest, TestGenericDistributedQueue, 
RequiredFieldsTest, TestTriggerIntegration, SolrGangliaReporterTest, 
SearchRateTriggerTest, TestNamedUpdateProcessors, TestReloadDeadlock, 
TestRawTransformer, TestBM25SimilarityFactory, TestInitParams, BlockCacheTest, 
StatsComponentTest, DistanceUnitsTest, TestMaxScoreQueryParser, 
TestSha256AuthenticationProvider, HdfsBasicDistributedZkTest, 
CloudExitableDirectoryReaderTest, TolerantUpdateProcessorTest, 
TestIBSimilarityFactory, TestEmbeddedSolrServerAdminHandler, ForceLeaderTest, 
TestTolerantUpdateProcessorCloud, HDFSCollectionsAPITest, JavabinLoaderTest, 
TestNodeAddedTrigger, TestCloudInspectUtil, TermVectorComponentDistributedTest, 
TestGraphMLResponseWriter, ExecutePlanActionTest, TestClassicSimilarityFactory, 
ConnectionReuseTest, TestNumericRangeQuery32, DeleteInactiveReplicaTest, 
TestZkAclsWithHadoopAuth, HdfsRestartWhileUpdatingTest, 
LeaderInitiatedRecoveryOnShardRestartTest, TestDocBasedVersionConstraints, 
UninvertDocValuesMergePolicyTest, TestSolrCoreSnapshots, 
TestSurroundQueryParser, TestDynamicFieldCollectionResource, 
CollectionTooManyReplicasTest, TestBackupRepositoryFactory, 
CoreAdminRequestStatusTest, InfoHandlerTest, ImplicitSnitchTest, 
TestConfigSetImmutable, TestCharFilters, TestInitQParser, 
BigEndianAscendingWordSerializerTest, 
StatelessScriptUpdateProcessorFactoryTest, BooleanFieldTest, 
DistributedVersionInfoTest, TestRTimerTree, TestLegacyFieldReuse, 
BasicAuthIntegrationTest, TestSQLHandlerNonCloud, 
ClassificationUpdateProcessorTest, DateFieldTest, 
TestImpersonationWithHadoopAuth, SearchHandlerTest, 
PeerSyncWithIndexFingerprintCachingTest, 
DocExpirationUpdateProcessorFactoryTest, SpatialFilterTest, 
PropertiesRequestHandlerTest, ZkStateReaderTest, QueryEqualityTest, 
TestMacroExpander, TestXmlQParser, WordBreakSolrSpellCheckerTest, 
TestFieldTypeCollectionResource, ReplicaListTransformerTest, 
SolrShardReporterTest, NodeAddedTriggerTest, QueryElevationComponentTest, 
TestNestedDocsSort, RecoveryZkTest, RulesTest, TestRandomCollapseQParserPlugin, 
SchemaApiFailureTest, TestImplicitCoreProperties, TestClusterStateProvider, 
TestSchemaNameResource, SolrMetricReporterTest, TestPerFieldSimilarity, 
HdfsDirectoryFactoryTest, LeaderElectionIntegrationTest, 
DistribJoinFromCollectionTest, TestCSVResponseWriter, NumericFieldsTest, 
TestPartialUpdateDeduplication, SOLR749Test, DirectSolrSpellCheckerTest, 
LeaderElectionTest, BasicZkTest, HttpTriggerListenerTest, TestUtilizeNode]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestUtilizeNode 
-Dtests.seed=1393656331314A8C -Dtests.slow=true -Dtests.locale=es-NI 
-Dtests.timezone=America/Indianapolis -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | TestUtilizeNode (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.cloud.TestUtilizeNode: 
   [junit4]    >    1) Thread[id=9482, name=qtp270146496-9482, 
state=TIMED_WAITING, group=TGRP-TestUtilizeNode]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1393656331314A8C]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=9482, name=qtp270146496-9482, 
state=TIMED_WAITING, group=TGRP-TestUtilizeNode]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1393656331314A8C]:0)
   [junit4] Completed [216/776 (1!)] on J0 in 66.63s, 1 test, 2 errors <<< 
FAILURES!

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

Reply via email to