Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21495/
Java: 64bit/jdk-10-ea+43 -XX:+UseCompressedOops -XX:+UseParallelGC

No tests ran.

Build Log:
[...truncated 12285 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
   [junit4]   2> 98135 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/init-core-data-001
   [junit4]   2> 98136 WARN  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 98136 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 98136 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 98137 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001
   [junit4]   2> 98138 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 98138 INFO  (Thread-194) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 98138 INFO  (Thread-194) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 98148 ERROR (Thread-194) [    ] 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> 98238 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:39985
   [junit4]   2> 98240 INFO  (zkConnectionManagerCallback-266-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98243 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 98243 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 98243 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 98243 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 98243 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 98244 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@756462df{/solr,null,AVAILABLE}
   [junit4]   2> 98244 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 98244 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 98244 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 98244 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@60d3903c{/solr,null,AVAILABLE}
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3933ce2c{SSL,[ssl, 
http/1.1]}{127.0.0.1:38995}
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@5755fe48{SSL,[ssl, 
http/1.1]}{127.0.0.1:43795}
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-1) [    ] 
o.e.j.s.Server Started @100074ms
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-2) [    ] 
o.e.j.s.Server Started @100074ms
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43795}
   [junit4]   2> 98245 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38995}
   [junit4]   2> 98246 ERROR (jetty-launcher-263-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 98246 ERROR (jetty-launcher-263-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:11.772911Z
   [junit4]   2> 98246 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:11.772902Z
   [junit4]   2> 98248 INFO  (zkConnectionManagerCallback-269-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98248 INFO  (zkConnectionManagerCallback-270-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98248 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 98248 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 98252 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 
0x1006ec6f30c0002, likely client has closed socket
   [junit4]   2> 98253 INFO  (jetty-launcher-263-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 98256 INFO  (zkConnectionManagerCallback-274-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98256 INFO  (jetty-launcher-263-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 98264 INFO  (zkConnectionManagerCallback-278-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98268 INFO  
(zkConnectionManagerCallback-280-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98284 INFO  
(zkConnectionManagerCallback-282-thread-1-processing-n:127.0.0.1:38995_solr) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98288 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 98289 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:38995_solr
   [junit4]   2> 98289 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.Overseer Overseer 
(id=72179394798157830-127.0.0.1:38995_solr-n_0000000000) starting
   [junit4]   2> 98303 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38995_solr
   [junit4]   2> 98303 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Adding 
.autoAddReplicas trigger
   [junit4]   2> 98304 INFO  
(zkCallback-281-thread-1-processing-n:127.0.0.1:38995_solr) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 98304 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 1
   [junit4]   2> 98305 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:38995_solr]
   [junit4]   2> 98305 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion -1
   [junit4]   2> 98305 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 1
   [junit4]   2> 98308 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 98310 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 98311 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43795_solr
   [junit4]   2> 98312 INFO  
(zkCallback-281-thread-1-processing-n:127.0.0.1:38995_solr) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 98340 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 98340 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 98341 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 98341 DEBUG (ScheduledTrigger-331-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 2
   [junit4]   2> 98369 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43795.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98377 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43795.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98377 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38995.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98380 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43795.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98382 INFO  (jetty-launcher-263-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node2/.
   [junit4]   2> 98382 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38995.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98382 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38995.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 98383 INFO  (jetty-launcher-263-thread-1) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node1/.
   [junit4]   2> 98440 INFO  (zkConnectionManagerCallback-288-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98444 INFO  (zkConnectionManagerCallback-292-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 98445 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 98445 INFO  
(SUITE-TriggerIntegrationTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39985/solr ready
   [junit4]   2> 98456 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSearchRate
   [junit4]   2> 98472 INFO  (qtp1505803726-966) [n:127.0.0.1:38995_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 98483 INFO  (qtp1505803726-966) [n:127.0.0.1:38995_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=11
   [junit4]   2> 98484 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
   [junit4]   2> 98485 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
   [junit4]   2> 98485 DEBUG 
(zkCallback-281-thread-1-processing-n:127.0.0.1:38995_solr) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 2
   [junit4]   2> 98486 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 2
   [junit4]   2> 98486 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 98486 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 98486 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 2, lastZnodeVersion 2
   [junit4]   2> 98493 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@3933ce2c{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 98493 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1348088825
   [junit4]   2> 98494 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 98494 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5618d949: rootName = 
solr_38995, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@3c86bc94
   [junit4]   2> 98498 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 98498 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1674987d: rootName = 
solr_38995, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@2516bbbe
   [junit4]   2> 98501 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 98501 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@51cad75e: rootName = 
solr_38995, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@393c02ab
   [junit4]   2> 98502 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38995_solr
   [junit4]   2> 98503 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 98503 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 98503 INFO  
(zkCallback-281-thread-1-processing-n:127.0.0.1:38995_solr) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 98503 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 98503 INFO  (zkCallback-291-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 98503 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.Overseer Overseer 
(id=72179394798157830-127.0.0.1:38995_solr-n_0000000000) closing
   [junit4]   2> 98503 INFO  
(OverseerStateUpdate-72179394798157830-127.0.0.1:38995_solr-n_0000000000) 
[n:127.0.0.1:38995_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:38995_solr
   [junit4]   2> 98503 WARN  
(OverseerAutoScalingTriggerThread-72179394798157830-127.0.0.1:38995_solr-n_0000000000)
 [n:127.0.0.1:38995_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 98504 DEBUG 
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 98505 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:43795_solr
   [junit4]   2> 98505 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@756462df{/solr,null,UNAVAILABLE}
   [junit4]   2> 98505 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 98505 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.Overseer Overseer 
(id=72179394798157829-127.0.0.1:43795_solr-n_0000000001) starting
   [junit4]   2> 98508 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Adding 
.autoAddReplicas trigger
   [junit4]   2> 98508 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 3
   [junit4]   2> 98508 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:43795_solr]
   [junit4]   2> 98508 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 3, lastZnodeVersion -1
   [junit4]   2> 98508 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 3
   [junit4]   2> 98509 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 98509 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 3, lastZnodeVersion 3
   [junit4]   2> 98509 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 99509 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 100510 DEBUG (ScheduledTrigger-340-thread-2) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 101510 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 102510 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 103507 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 103508 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 103508 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 103508 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 103509 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@78e9d6cb{/solr,null,AVAILABLE}
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@672775aa{SSL,[ssl, 
http/1.1]}{127.0.0.1:34159}
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server Started @105339ms
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34159}
   [junit4]   2> 103510 ERROR 
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 103510 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:17.036889Z
   [junit4]   2> 103510 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 1
   [junit4]   2> 103513 INFO  (zkConnectionManagerCallback-294-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103514 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 103521 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 103522 INFO  (zkConnectionManagerCallback-298-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103524 INFO  
(zkConnectionManagerCallback-300-thread-1-processing-n:127.0.0.1:34159_solr) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103527 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 103527 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 103528 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34159_solr
   [junit4]   2> 103529 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 103529 INFO  (zkCallback-291-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 103533 INFO  
(zkCallback-299-thread-1-processing-n:127.0.0.1:34159_solr) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 103564 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34159.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103569 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34159.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103570 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34159.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103571 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node3/.
   [junit4]   2> 103591 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 103592 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 103592 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 103592 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 103593 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5b3e0b98{/solr,null,AVAILABLE}
   [junit4]   2> 103593 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1d5bef0e{SSL,[ssl, 
http/1.1]}{127.0.0.1:46661}
   [junit4]   2> 103593 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server Started @105423ms
   [junit4]   2> 103593 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46661}
   [junit4]   2> 103594 ERROR 
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 103594 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 103594 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 103594 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 103594 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:17.120377Z
   [junit4]   2> 103595 INFO  (zkConnectionManagerCallback-304-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103596 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 103602 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 103603 INFO  (zkConnectionManagerCallback-308-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103605 INFO  
(zkConnectionManagerCallback-310-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103608 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 103609 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 103610 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46661_solr
   [junit4]   2> 103611 INFO  
(zkCallback-299-thread-1-processing-n:127.0.0.1:34159_solr) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 103611 INFO  (zkCallback-291-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 103611 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 103611 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 103655 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46661.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103660 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46661.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103661 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46661.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103661 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node4/.
   [junit4]   2> 103699 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 103700 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 103700 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 103700 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 103700 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@434d9e1a{/solr,null,AVAILABLE}
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@59be6d94{SSL,[ssl, 
http/1.1]}{127.0.0.1:45375}
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server Started @105530ms
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45375}
   [junit4]   2> 103701 ERROR 
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 103701 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:17.227879Z
   [junit4]   2> 103703 INFO  (zkConnectionManagerCallback-314-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103703 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 103708 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 103709 INFO  (zkConnectionManagerCallback-318-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103711 INFO  
(zkConnectionManagerCallback-320-thread-1-processing-n:127.0.0.1:45375_solr) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103713 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 103714 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 103715 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45375_solr
   [junit4]   2> 103715 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 103715 INFO  
(zkCallback-299-thread-1-processing-n:127.0.0.1:34159_solr) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 103715 INFO  (zkCallback-291-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 103715 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 103717 INFO  
(zkCallback-319-thread-1-processing-n:127.0.0.1:45375_solr) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 103740 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45375.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103746 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45375.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103746 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45375.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103747 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node5/.
   [junit4]   2> 103776 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 103776 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 103776 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 103776 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@39bf459d{/solr,null,AVAILABLE}
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@64cadbf0{SSL,[ssl, 
http/1.1]}{127.0.0.1:32867}
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.e.j.s.Server Started @105606ms
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=32867}
   [junit4]   2> 103777 ERROR 
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 103777 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-20T02:56:17.303859Z
   [junit4]   2> 103778 INFO  (zkConnectionManagerCallback-324-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103779 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 103784 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39985/solr
   [junit4]   2> 103785 INFO  (zkConnectionManagerCallback-328-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103789 INFO  
(zkConnectionManagerCallback-330-thread-1-processing-n:127.0.0.1:32867_solr) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 103792 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 103793 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 103795 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:32867_solr
   [junit4]   2> 103796 INFO  
(zkCallback-299-thread-1-processing-n:127.0.0.1:34159_solr) 
[n:127.0.0.1:34159_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 103796 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 103796 INFO  (zkCallback-291-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 103796 INFO  
(zkCallback-319-thread-1-processing-n:127.0.0.1:45375_solr) 
[n:127.0.0.1:45375_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 103796 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 103801 INFO  
(zkCallback-329-thread-1-processing-n:127.0.0.1:32867_solr) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 103835 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_32867.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103841 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_32867.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103841 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_32867.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 103842 INFO  
(TEST-TriggerIntegrationTest.testSearchRate-seed#[AC9A42B9D9A7C190]) 
[n:127.0.0.1:32867_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node6/.
   [junit4]   2> 103887 INFO  (qtp132218198-1109) [n:127.0.0.1:32867_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&name=collection1&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 103889 INFO  
(OverseerThreadFactory-342-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 103995 INFO  
(OverseerStateUpdate-72179394798157829-127.0.0.1:43795_solr-n_0000000001) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:43795/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 103996 INFO  
(OverseerStateUpdate-72179394798157829-127.0.0.1:43795_solr-n_0000000001) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:46661/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 104214 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 104214 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 104215 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 104215 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 104321 INFO  
(zkCallback-279-thread-1-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 104321 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 104321 INFO  
(zkCallback-279-thread-2-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 104321 INFO  
(zkCallback-309-thread-2-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 104511 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 5
   [junit4]   2> 105226 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 105226 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 105234 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 105234 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 105235 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 105235 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 105235 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 105235 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 105236 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46661.solr.core.collection1.shard1.replica_n2' (registry 
'solr.core.collection1.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 105236 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 105236 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node4/collection1_shard1_replica_n2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node4/./collection1_shard1_replica_n2/data/]
   [junit4]   2> 105236 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43795.solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4fe10f7a
   [junit4]   2> 105236 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 105236 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node2/collection1_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_AC9A42B9D9A7C190-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
   [junit4]   2> 105273 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 105273 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 105274 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 105274 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 105276 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@97da0d0[collection1_shard1_replica_n2] main]
   [junit4]   2> 105276 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 105277 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 105277 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 105278 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592886955924783104
   [junit4]   2> 105280 INFO  
(searcherExecutor-367-thread-1-processing-n:127.0.0.1:46661_solr 
x:collection1_shard1_replica_n2 s:shard1 c:collection1 r:core_node4) 
[n:127.0.0.1:46661_solr c:collection1 s:shard1 r:core_node4 
x:collection1_shard1_replica_n2] o.a.s.c.SolrCore 
[collection1_shard1_replica_n2] Registered new searcher 
Searcher@97da0d0[collection1_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 105283 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 105283 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 105284 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 105284 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 105285 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_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> 105285 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@72f2ec7e[collection1_shard1_replica_n1] main]
   [junit4]   2> 105286 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 105286 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 105287 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 105288 INFO  
(searcherExecutor-368-thread-1-processing-n:127.0.0.1:43795_solr 
x:collection1_shard1_replica_n1 s:shard1 c:collection1 r:core_node3) 
[n:127.0.0.1:43795_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@72f2ec7e[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 105288 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1592886955935268864
   [junit4]   2> 105512 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 5
   [junit4]   2> 105785 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 105785 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 105785 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:46661/solr/collection1_shard1_replica_n2/
   [junit4]   2> 105785 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n2 
url=https://127.0.0.1:46661/solr START 
replicas=[https://127.0.0.1:43795/solr/collection1_shard1_replica_n1/] 
nUpdates=100
   [junit4]   2> 105791 INFO  (qtp1453831937-975) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 106086 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n2 
url=https://127.0.0.1:46661/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 106086 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 106086 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_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> 106086 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 106088 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:46661/solr/collection1_shard1_replica_n2/ shard1
   [junit4]   2> 106512 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 5
   [junit4]   2> 107322 INFO  
(zkCallback-279-thread-2-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107322 INFO  
(zkCallback-309-thread-2-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107322 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107322 INFO  
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107424 INFO  
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107424 INFO  
(zkCallback-309-thread-2-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107424 INFO  
(zkCallback-279-thread-2-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107424 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107440 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 107512 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 5
   [junit4]   2> 107545 INFO  
(zkCallback-279-thread-2-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107545 INFO  
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107545 INFO  
(zkCallback-309-thread-1-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 107545 INFO  
(zkCallback-309-thread-2-processing-n:127.0.0.1:46661_solr) 
[n:127.0.0.1:46661_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [5])
   [junit4]   2> 108512 DEBUG (ScheduledTrigger-340-thread-1) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 5
   [junit4]   2> 108798 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4584
   [junit4]   2> 108803 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4588
   [junit4]   2> 108806 INFO  (qtp132218198-1109) [n:127.0.0.1:32867_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> 108807 INFO  (qtp132218198-1109) [n:127.0.0.1:32867_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=collection1&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=4919
   [junit4]   2> 108844 INFO  (qtp132218198-1109) [n:127.0.0.1:32867_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=6
   [junit4]   2> 108850 INFO  (qtp1446878127-1062) [n:127.0.0.1:46661_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 108853 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_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=1
   [junit4]   2> 108858 INFO  (qtp1592206152-1033) [n:127.0.0.1:34159_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> 108862 INFO  (qtp1453831937-974) [n:127.0.0.1:43795_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 108863 INFO  (qtp1453831937-975) [n:127.0.0.1:43795_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> 108879 INFO  (qtp561952100-1085) [n:127.0.0.1:45375_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> 108880 DEBUG (qtp1592206152-1037) [n:127.0.0.1:34159_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 108881 INFO  (qtp1592206152-1037) [n:127.0.0.1:34159_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=55
   [junit4]   2> 108881 DEBUG 
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 4
   [junit4]   2> 108884 DEBUG 
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:32867_solr, 127.0.0.1:46661_solr, 127.0.0.1:34159_solr, 
127.0.0.1:43795_solr, 127.0.0.1:45375_solr]
   [junit4]   2> 108884 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 4
   [junit4]   2> 108886 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 108886 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 4, lastZnodeVersion 4
   [junit4]   2> 108891 INFO  (qtp1446878127-1058) [n:127.0.0.1:46661_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n2:QUERY./select.requestTimes:1minRate}
 status=0 QTime=1
   [junit4]   2> 108892 INFO  (qtp132218198-1105) [n:127.0.0.1:32867_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> 108896 INFO  (qtp1446878127-1058) [n:127.0.0.1:46661_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 108896 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:QUERY./select.requestTimes:1minRate}
 status=0 QTime=0
   [junit4]   2> 108898 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_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> 108904 INFO  (qtp1592206152-1033) [n:127.0.0.1:34159_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> 108908 INFO  (qtp1453831937-976) [n:127.0.0.1:43795_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 108909 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_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> 108917 INFO  (qtp561952100-1083) [n:127.0.0.1:45375_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> 108917 DEBUG (qtp561952100-1085) [n:127.0.0.1:45375_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 108918 INFO  (qtp561952100-1085) [n:127.0.0.1:45375_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=32
   [junit4]   2> 108918 DEBUG 
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 5
   [junit4]   2> 108918 DEBUG 
(zkCallback-279-thread-3-processing-n:127.0.0.1:43795_solr) 
[n:127.0.0.1:43795_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:32867_solr, 127.0.0.1:46661_solr, 127.0.0.1:34159_solr, 
127.0.0.1:43795_solr, 127.0.0.1:45375_solr]
   [junit4]   2> 108919 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 5
   [junit4]   2> 108919 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 108919 DEBUG 
(OverseerAutoScalingTriggerThread-72179394798157829-127.0.0.1:43795_solr-n_0000000001)
 [n:127.0.0.1:43795_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 5, lastZnodeVersion 5
   [junit4]   2> 108921 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108922 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108923 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108924 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108925 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108926 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108927 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108930 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108931 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108932 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108932 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108933 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108934 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108935 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108936 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108936 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108937 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108938 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108939 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108940 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108941 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108941 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108942 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108943 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108944 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108945 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108946 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108946 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108947 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108948 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108949 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108949 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108950 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108951 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108952 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108952 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108953 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108954 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108955 INFO  (qtp1446878127-1063) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108955 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108956 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108957 INFO  (qtp1446878127-1061) [n:127.0.0.1:46661_solr 
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2] 
o.a.s.c.S.Request [collection1_shard1_replica_n2]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108958 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108959 INFO  (qtp1453831937-971) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0 status=0 
QTime=0
   [junit4]   2> 108959 INFO  (qtp1453831937-970) [n:127.0.0.1:43795_solr 
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection1:5&wt=javabin&version=2} hits=0

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

(coreCloseExecutor-9029-thread-2) [n:127.0.0.1:36123_solr c:testNodeAdded 
s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.testNodeAdded.shard1.leader, tag=606463324
   [junit4]   2> 2798908 INFO  (coreCloseExecutor-9029-thread-1) 
[n:127.0.0.1:36123_solr c:testNodeAdded s:shard1 r:core_node4 
x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.testNodeAdded.shard1.leader, 
tag=2044215495
   [junit4]   2> 2798909 INFO  (jetty-closer-7880-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=72179569168285715-127.0.0.1:36123_solr-n_0000000004) closing
   [junit4]   2> 2798909 INFO  
(OverseerStateUpdate-72179569168285715-127.0.0.1:36123_solr-n_0000000004) 
[n:127.0.0.1:36123_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:36123_solr
   [junit4]   2> 2798909 WARN  
(OverseerAutoScalingTriggerThread-72179569168285715-127.0.0.1:36123_solr-n_0000000004)
 [n:127.0.0.1:36123_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2798910 DEBUG (jetty-closer-7880-thread-1) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 2798911 INFO  (jetty-closer-7880-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@500700e3{/solr,null,UNAVAILABLE}
   [junit4]   2> 2798911 INFO  (jetty-closer-7880-thread-1) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 2798912 ERROR 
(SUITE-ComputePlanActionTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
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> 2798912 INFO  
(SUITE-ComputePlanActionTest-seed#[AC9A42B9D9A7C190]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35787 35787
   [junit4]   2> 2803934 INFO  (Thread-6870) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35787 35787
   [junit4]   2> 2803934 WARN  (Thread-6870) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        15      /solr/aliases.json
   [junit4]   2>        15      /solr/clusterprops.json
   [junit4]   2>        11      
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard1
   [junit4]   2>        11      /solr/configs/conf
   [junit4]   2>        10      /solr/security.json
   [junit4]   2>        9       
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard2
   [junit4]   2>        6       /solr/collections/testNodeLost/terms/shard1
   [junit4]   2>        5       /solr/collections/testSelected1/terms/shard2
   [junit4]   2>        5       /solr/collections/testSelected2/terms/shard1
   [junit4]   2>        5       /solr/collections/testSelected1/terms/shard1
   [junit4]   2>        5       /solr/collections/testSelected2/terms/shard2
   [junit4]   2>        5       /solr/collections/testSelected3/terms/shard2
   [junit4]   2>        5       /solr/collections/testSelected3/terms/shard1
   [junit4]   2>        3       /solr/collections/testNodeAdded/terms/shard1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        43      
/solr/collections/testNodeWithMultipleReplicasLost/state.json
   [junit4]   2>        26      /solr/collections/testSelected1/state.json
   [junit4]   2>        26      /solr/collections/testSelected2/state.json
   [junit4]   2>        25      /solr/collections/testSelected3/state.json
   [junit4]   2>        16      /solr/collections/testNodeLost/state.json
   [junit4]   2>        15      /solr/clusterstate.json
   [junit4]   2>        7       /solr/collections/testNodeAdded/state.json
   [junit4]   2>        4       
/solr/overseer_elect/election/72179569168285699-127.0.0.1:45935_solr-n_0000000000
   [junit4]   2>        4       
/solr/overseer_elect/election/72179569168285715-127.0.0.1:36123_solr-n_0000000004
   [junit4]   2>        2       
/solr/collections/testNodeWithMultipleReplicasLost/leader_elect/shard2/election/72179569168285718-core_node9-n_0000000000
   [junit4]   2>        2       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        53      /solr/live_nodes
   [junit4]   2>        48      /solr/collections
   [junit4]   2>        2       /solr/overseer/queue
   [junit4]   2>        2       /solr/autoscaling/events/.auto_add_replicas
   [junit4]   2>        2       /solr/overseer/collection-queue-work
   [junit4]   2>        2       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_AC9A42B9D9A7C190-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1886bdb5),
 locale=bs-Latn-BA, timezone=Africa/Douala
   [junit4]   2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation 10 
(64-bit)/cpus=8,threads=1,free=201969408,total=520093696
   [junit4]   2> NOTE: All tests run in this JVM: [TestSort, 
TestCollationField, TestCloudInspectUtil, CdcrVersionReplicationTest, 
DeleteLastCustomShardedReplicaTest, TestFastOutputStream, 
SharedFSAutoReplicaFailoverTest, MigrateRouteKeyTest, TestReRankQParserPlugin, 
TestCloudPseudoReturnFields, TestSolr4Spatial2, 
DocumentAnalysisRequestHandlerTest, TestClusterProperties, TestNumericTerms32, 
TestBinaryField, TriggerIntegrationTest, TestPhraseSuggestions, 
TestSolrDeletionPolicy1, TestDynamicLoading, FieldAnalysisRequestHandlerTest, 
TestLeaderInitiatedRecoveryThread, MergeStrategyTest, 
TestManagedSchemaThreadSafety, TestGeoJSONResponseWriter, 
TestMergePolicyConfig, DistributedSuggestComponentTest, TestScoreJoinQPNoScore, 
TermVectorComponentDistributedTest, BasicAuthIntegrationTest, 
MBeansHandlerTest, TestSegmentSorting, HdfsBasicDistributedZkTest, 
TestIndexSearcher, TestComplexPhraseQParserPlugin, 
TestMultiValuedNumericRangeQuery, TestSerializedLuceneMatchVersion, 
TestIndexingPerformance, TestFilteredDocIdSet, TestComponentsName, 
V2ApiIntegrationTest, TestHdfsBackupRestoreCore, 
LeaderFailureAfterFreshStartTest, TestHighlightDedupGrouping, 
LIRRollingUpdatesTest, SolrSlf4jReporterTest, VersionInfoTest, 
ZkStateWriterTest, ZkNodePropsTest, TestPushWriter, 
TestSha256AuthenticationProvider, TestStressLiveNodes, InfoHandlerTest, 
DataDrivenBlockJoinTest, TestAddFieldRealTimeGet, DistanceUnitsTest, 
TestExclusionRuleCollectionAccess, UUIDFieldTest, StatsComponentTest, 
TestManagedSynonymFilterFactory, TestInitParams, TestSchemalessBufferedUpdates, 
BinaryUpdateRequestHandlerTest, SolrPluginUtilsTest, TestCloudManagedSchema, 
CoreAdminCreateDiscoverTest, TestAnalyzeInfixSuggestions, EchoParamsTest, 
RAMDirectoryFactoryTest, TestDynamicFieldResource, TestCrossCoreJoin, 
DistributedFacetPivotWhiteBoxTest, BigEndianAscendingWordDeserializerTest, 
DistributedExpandComponentTest, TestJoin, SolrIndexSplitterTest, 
ReplaceNodeTest, DirectUpdateHandlerTest, TestAuthorizationFramework, 
RequestHandlersTest, DistributedVersionInfoTest, 
TestEmbeddedSolrServerSchemaAPI, AssignBackwardCompatibilityTest, 
TestSolrCloudWithSecureImpersonation, TestDocBasedVersionConstraints, 
RecoveryAfterSoftCommitTest, NodeMutatorTest, 
DistributedQueryElevationComponentTest, TestConfig, BlockJoinFacetDistribTest, 
TestDistribStateManager, OutputWriterTest, SearchRateTriggerTest, 
ShardRoutingTest, TestPolicyCloud, TestUnifiedSolrHighlighter, TestMacros, 
FullSolrCloudDistribCmdsTest, SynonymTokenizerTest, 
DistributedTermsComponentTest, SolrIndexConfigTest, TestDistributedSearch, 
BadIndexSchemaTest, TestSkipOverseerOperations, CurrencyRangeFacetCloudTest, 
AtomicUpdatesTest, TestFieldTypeCollectionResource, TestRestoreCore, 
TestInPlaceUpdatesDistrib, HdfsRestartWhileUpdatingTest, 
DistribDocExpirationUpdateProcessorTest, ExplicitHLLTest, TestExpandComponent, 
TestShardHandlerFactory, OpenCloseCoreStressTest, TestGroupingSearch, 
TestSolrQueryParser, MetricsConfigTest, RegexBytesRefFilterTest, 
AddBlockUpdateTest, TestCoreAdminApis, TestXmlQParser, SuggesterWFSTTest, 
TestOrdValues, TestFreeTextSuggestions, OverseerStatusTest, 
TestSolrCloudWithHadoopAuthPlugin, BasicZkTest, ClusterStateUpdateTest, 
TestRandomFaceting, ZkCLITest, SolrCmdDistributorTest, 
QueryElevationComponentTest, HighlighterTest, SoftAutoCommitTest, 
PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, 
TestSolrJ, TestDocumentBuilder, SystemInfoHandlerTest, FileUtilsTest, 
CircularListTest, CursorPagingTest, TestDistributedMissingSort, 
TestSimpleTrackingShardHandler, TestLuceneIndexBackCompat, 
TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, 
TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, 
AliasIntegrationTest, CloudExitableDirectoryReaderTest, 
ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, ConnectionManagerTest, 
CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DeleteReplicaTest, 
DistribCursorPagingTest, HealthCheckHandlerTest, HttpPartitionOnCommitTest, 
LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnShardRestartTest, 
MissingSegmentRecoveryTest, MoveReplicaHDFSTest, OverseerTaskQueueTest, 
RemoteQueryErrorTest, ReplicationFactorTest, RollingRestartTest, 
SSLMigrationTest, SaslZkACLProviderTest, SolrXmlInZkTest, 
TestCloudJSONFacetJoinDomain, TestConfigSetsAPIZkFailure, 
TestDistribDocBasedVersion, TestLeaderElectionWithEmptyReplica, 
TestPullReplica, TestSSLRandomization, TestSizeLimitedDistributedMap, 
TestTlogReplica, CollectionsAPIAsyncDistributedZkTest, 
ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, 
TestCollectionAPI, TestCollectionsAPIViaSolrCloudCluster, 
TestRequestStatusCollectionAPI, AutoAddReplicasPlanActionTest, 
ComputePlanActionTest]
   [junit4] Completed [591/778 (4!)] on J0 in 45.12s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 41892 lines...]
ERROR: Step ‘Publish JUnit test result report’ failed: No test report files 
were found. Configuration error?
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to