Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1181/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseSerialGC

2 tests failed.
FAILED:  org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi

Error Message:
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([992133C0B38D6916:CE08C875687F8B0D]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:725)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.cloud.autoscaling.sim.TestClusterStateProvider.testAutoScalingConfig

Error Message:
expected: org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{   
"cluster-preferences":[{"maximize":"freedisk"}],   
"triggers":{".auto_add_replicas":{       "name":".auto_add_replicas",       
"event":"nodeLost",       "waitFor":30,       "actions":[         {           
"name":"auto_add_replicas_plan",           
"class":"solr.AutoAddReplicasPlanAction"},         {           
"name":"execute_plan",           "class":"solr.ExecutePlanAction"}],       
"enabled":true}},   "listeners":{".auto_add_replicas.system":{       
"trigger":".auto_add_replicas",       "afterAction":[],       "stage":[         
"STARTED",         "ABORTED",         "SUCCEEDED",         "FAILED",         
"BEFORE_ACTION",         "AFTER_ACTION",         "IGNORED"],       
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",       
"beforeAction":[]}},   "properties":{}}> but was: 
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{   
"cluster-preferences":[{"maximize":"freedisk"}],   
"triggers":{".auto_add_replicas":{       "name":".auto_add_replicas",       
"event":"nodeLost",       "waitFor":30,       "actions":[         {           
"name":"auto_add_replicas_plan",           
"class":"solr.AutoAddReplicasPlanAction"},         {           
"name":"execute_plan",           "class":"solr.ExecutePlanAction"}],       
"enabled":true}},   "listeners":{".auto_add_replicas.system":{       
"trigger":".auto_add_replicas",       "afterAction":[],       "stage":[         
"STARTED",         "ABORTED",         "SUCCEEDED",         "FAILED",         
"BEFORE_ACTION",         "AFTER_ACTION",         "IGNORED"],       
"class":"org.apache.solr.cloud.autoscaling.SystemLogListener",       
"beforeAction":[]}},   "properties":{}}>

Stack Trace:
java.lang.AssertionError: expected: 
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
  "cluster-preferences":[{"maximize":"freedisk"}],
  "triggers":{".auto_add_replicas":{
      "name":".auto_add_replicas",
      "event":"nodeLost",
      "waitFor":30,
      "actions":[
        {
          "name":"auto_add_replicas_plan",
          "class":"solr.AutoAddReplicasPlanAction"},
        {
          "name":"execute_plan",
          "class":"solr.ExecutePlanAction"}],
      "enabled":true}},
  "listeners":{".auto_add_replicas.system":{
      "trigger":".auto_add_replicas",
      "afterAction":[],
      "stage":[
        "STARTED",
        "ABORTED",
        "SUCCEEDED",
        "FAILED",
        "BEFORE_ACTION",
        "AFTER_ACTION",
        "IGNORED"],
      "class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
      "beforeAction":[]}},
  "properties":{}}> but was: 
org.apache.solr.client.solrj.cloud.autoscaling.AutoScalingConfig<{
  "cluster-preferences":[{"maximize":"freedisk"}],
  "triggers":{".auto_add_replicas":{
      "name":".auto_add_replicas",
      "event":"nodeLost",
      "waitFor":30,
      "actions":[
        {
          "name":"auto_add_replicas_plan",
          "class":"solr.AutoAddReplicasPlanAction"},
        {
          "name":"execute_plan",
          "class":"solr.ExecutePlanAction"}],
      "enabled":true}},
  "listeners":{".auto_add_replicas.system":{
      "trigger":".auto_add_replicas",
      "afterAction":[],
      "stage":[
        "STARTED",
        "ABORTED",
        "SUCCEEDED",
        "FAILED",
        "BEFORE_ACTION",
        "AFTER_ACTION",
        "IGNORED"],
      "class":"org.apache.solr.cloud.autoscaling.SystemLogListener",
      "beforeAction":[]}},
  "properties":{}}>
        at 
__randomizedtesting.SeedInfo.seed([992133C0B38D6916:A6A93068A4DB99F1]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:147)
        at 
org.apache.solr.cloud.autoscaling.sim.TestClusterStateProvider.testAutoScalingConfig(TestClusterStateProvider.java:214)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12772 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/init-core-data-001
   [junit4]   2> 887356 WARN  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 887356 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 887357 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 887357 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 887358 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/tempDir-001
   [junit4]   2> 887358 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 887358 INFO  (Thread-1638) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 887358 INFO  (Thread-1638) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 887359 ERROR (Thread-1638) [    ] 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> 887458 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:41063
   [junit4]   2> 887462 INFO  (zkConnectionManagerCallback-1916-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887467 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 887467 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 887469 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@c80e12{/solr,null,AVAILABLE}
   [junit4]   2> 887469 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@8dc81{/solr,null,AVAILABLE}
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@37ad98{HTTP/1.1,[http/1.1]}{127.0.0.1:40527}
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@123a320{HTTP/1.1,[http/1.1]}{127.0.0.1:46779}
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.e.j.s.Server Started @888715ms
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.e.j.s.Server Started @888715ms
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40527}
   [junit4]   2> 887471 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46779}
   [junit4]   2> 887471 ERROR (jetty-launcher-1913-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 887471 ERROR (jetty-launcher-1913-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.0
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.0
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-15T09:14:05.612Z
   [junit4]   2> 887472 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-15T09:14:05.612Z
   [junit4]   2> 887475 INFO  (zkConnectionManagerCallback-1919-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887475 INFO  (zkConnectionManagerCallback-1920-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887476 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 887476 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 887492 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x160f918e13b0002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 887497 INFO  (jetty-launcher-1913-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41063/solr
   [junit4]   2> 887497 INFO  (jetty-launcher-1913-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41063/solr
   [junit4]   2> 887499 INFO  (zkConnectionManagerCallback-1926-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887500 INFO  (zkConnectionManagerCallback-1928-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887502 INFO  
(zkConnectionManagerCallback-1930-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887503 INFO  
(zkConnectionManagerCallback-1932-thread-1-processing-n:127.0.0.1:40527_solr) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887562 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 887562 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 887563 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:46779_solr
   [junit4]   2> 887563 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40527_solr
   [junit4]   2> 887563 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.Overseer Overseer 
(id=99353077055422469-127.0.0.1:46779_solr-n_0000000000) starting
   [junit4]   2> 887564 INFO  
(zkCallback-1931-thread-1-processing-n:127.0.0.1:40527_solr) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 887564 INFO  
(zkCallback-1929-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 887568 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46779_solr
   [junit4]   2> 887568 INFO  
(zkCallback-1929-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 887569 INFO  
(zkCallback-1931-thread-1-processing-n:127.0.0.1:40527_solr) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 887690 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46779.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887699 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46779.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887699 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46779.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887700 INFO  (jetty-launcher-1913-thread-2) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/tempDir-001/node2/.
   [junit4]   2> 887783 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40527.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887791 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40527.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887791 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40527.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 887792 INFO  (jetty-launcher-1913-thread-1) 
[n:127.0.0.1:40527_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/tempDir-001/node1/.
   [junit4]   2> 887817 INFO  (zkConnectionManagerCallback-1938-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887820 INFO  (zkConnectionManagerCallback-1942-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 887821 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 887821 INFO  
(SUITE-AutoScalingHandlerTest-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41063/solr ready
   [junit4]   2> 887830 INFO  
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[992133C0B38D6916]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testDeleteUsedPolicy
   [junit4]   2> 887852 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=1
   [junit4]   2> 887853 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 887855 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=22
   [junit4]   2> 887856 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete
 and sendToOCPQueue=true
   [junit4]   2> 887857 INFO  
(OverseerThreadFactory-3714-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.CreateCollectionCmd Create collection COLL1
   [junit4]   2> 887869 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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=3
   [junit4]   2> 887871 INFO  (qtp22235827-8697) [n:127.0.0.1:46779_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> 887880 INFO  
(OverseerThreadFactory-3714-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 212610752 sessionWrapper.createTime 212610743898938, 
this.sessionWrapper.createTime 212610743898938 
   [junit4]   2> 887988 INFO  
(OverseerStateUpdate-99353077055422469-127.0.0.1:46779_solr-n_0000000000) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"COLL1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"COLL1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:46779/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 888191 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 888191 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 888296 INFO  
(zkCallback-1929-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 888296 INFO  
(zkCallback-1929-thread-2-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 889202 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 889210 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema 
[COLL1_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 889213 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 889213 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.CoreContainer 
Creating SolrCore 'COLL1_shard1_replica_n1' using configuration from collection 
COLL1, trusted=true
   [junit4]   2> 889214 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46779.solr.core.COLL1.shard1.replica_n1' (registry 
'solr.core.COLL1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@a6ce51
   [junit4]   2> 889214 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 889214 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore 
[[COLL1_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/tempDir-001/node2/COLL1_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_992133C0B38D6916-001/tempDir-001/node2/./COLL1_shard1_replica_n1/data/]
   [junit4]   2> 889272 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 889272 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 889273 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 889273 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 889275 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14bf040[COLL1_shard1_replica_n1] 
main]
   [junit4]   2> 889275 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 889276 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 889276 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 889277 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1589649234897928192
   [junit4]   2> 889277 INFO  
(searcherExecutor-3719-thread-1-processing-n:127.0.0.1:46779_solr 
x:COLL1_shard1_replica_n1 s:shard1 c:COLL1 r:core_node2) 
[n:127.0.0.1:46779_solr c:COLL1 s:shard1 r:core_node2 
x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore [COLL1_shard1_replica_n1] 
Registered new searcher Searcher@14bf040[COLL1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:46779/solr/COLL1_shard1_replica_n1/
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:46779/solr/COLL1_shard1_replica_n1/ has no replicas
   [junit4]   2> 889280 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 889281 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:46779/solr/COLL1_shard1_replica_n1/ shard1
   [junit4]   2> 889383 INFO  
(zkCallback-1929-thread-2-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 889383 INFO  
(zkCallback-1929-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 889432 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 889466 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr 
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1275
   [junit4]   2> 889472 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 889536 INFO  
(zkCallback-1929-thread-1-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 889536 INFO  
(zkCallback-1929-thread-2-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/COLL1/state.json] for collection [COLL1] has occurred - 
updating... (live nodes size: [2])
   [junit4]   2> 889859 INFO  
(OverseerCollectionConfigSetProcessor-99353077055422469-127.0.0.1:46779_solr-n_0000000000)
 [n:127.0.0.1:46779_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 890507 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete}
 status=0 QTime=2650
   [junit4]   2> 890516 ERROR (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{remove-policy=nodelete, 
errorMessages=[policy : nodelete is being used by collection COLL1]}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 890517 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=400 QTime=4
   [junit4]   2> 890519 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=COLL1&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 890522 INFO  
(OverseerThreadFactory-3714-thread-2-processing-n:127.0.0.1:46779_solr) 
[n:127.0.0.1:46779_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 890524 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.COLL1.shard1.replica_n1, tag=null
   [junit4]   2> 890524 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@b29d72: rootName = 
solr_46779, domain = solr.core.COLL1.shard1.replica_n1, service url = null, 
agent id = null] for registry solr.core.COLL1.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@137329a
   [junit4]   2> 890551 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.c.SolrCore [COLL1_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@45bb0e
   [junit4]   2> 890552 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.COLL1.shard1.replica_n1, tag=4569870
   [junit4]   2> 890552 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.COLL1.shard1.leader, tag=4569870
   [junit4]   2> 890556 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=COLL1_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=32
   [junit4]   2> 891262 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=COLL1&action=DELETE&wt=javabin&version=2} status=0 QTime=743
   [junit4]   2> 891263 INFO  
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[992133C0B38D6916]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testDeleteUsedPolicy
   [junit4]   2> 891287 INFO  
(TEST-AutoScalingHandlerTest.testErrorHandling-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testErrorHandling
   [junit4]   2> 891291 ERROR (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY}, 
{shard=#EACH, node=#ANY}, {nodeRole=overseer, replica=0}], 
errorMessages=[replica is required in {
   [junit4]   2>   "shard":"#EACH",
   [junit4]   2>   "node":"#ANY"}]}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 891292 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=400 QTime=3
   [junit4]   2> 891293 INFO  
(TEST-AutoScalingHandlerTest.testErrorHandling-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testErrorHandling
   [junit4]   2> 891318 INFO  
(TEST-AutoScalingHandlerTest.testSetProperties-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSetProperties
   [junit4]   2> 891325 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_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> 891329 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_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> 891331 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=11
   [junit4]   2> 891335 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891339 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_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> 891342 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891343 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 891345 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891349 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891352 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891353 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 891356 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891361 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891363 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891365 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 891367 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891371 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891374 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891375 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 891377 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891378 INFO  
(TEST-AutoScalingHandlerTest.testSetProperties-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSetProperties
   [junit4]   2> 891398 INFO  
(TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSuspendTrigger
   [junit4]   2> 891400 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891401 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 891405 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_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> 891406 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_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> 891407 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891410 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891411 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891412 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891415 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_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> 891416 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891417 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891420 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891421 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891422 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891424 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_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> 891425 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_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> 891426 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891429 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_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> 891431 INFO  (qtp22235827-8685) [n:127.0.0.1:46779_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> 891431 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891433 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891434 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891435 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891435 INFO  
(TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSuspendTrigger
   [junit4]   2> 891447 INFO  
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[992133C0B38D6916]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
   [junit4]   2> 891448 ERROR (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{set-policy={xyz=[{replica=<2, shard=#EACH, 
node=#ANY}, {nodeRole=!overseer, replica=0}], policy1=[{cores=<2, node=#ANY}, 
{replica=<2, shard=#EACH, node=#ANY}]}, errorMessages=[cores is only allowed in 
'cluster-policy']}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 891449 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=400 QTime=1
   [junit4]   2> 891450 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891451 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891452 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891454 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891454 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891455 INFO  (qtp22235827-8685) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891457 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891458 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 891458 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891461 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 891462 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 891463 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891465 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} 
status=0 QTime=0
   [junit4]   2> 891466 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} 
status=0 QTime=0
   [junit4]   2> 891466 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891469 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core}
 status=0 QTime=0
   [junit4]   2> 891470 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core}
 status=0 QTime=0
   [junit4]   2> 891471 INFO  (qtp22235827-8685) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891471 INFO  
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[992133C0B38D6916]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
   [junit4]   2> 891484 INFO  
(TEST-AutoScalingHandlerTest.test-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 891487 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891488 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_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> 891488 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 891491 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891492 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891492 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891497 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_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> 891498 INFO  (qtp22235827-8685) [n:127.0.0.1:46779_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> 891499 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 891500 ERROR (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{remove-trigger={name=node_lost_trigger}, 
errorMessages=[Cannot remove trigger: node_lost_trigger because it has active 
listeners: [xyz, node_lost_trigger.system]]}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 891501 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 891503 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_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> 891504 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891504 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891506 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_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> 891507 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891508 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891509 ERROR (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: 
Error in command payload, errors: [{set-listener={name=xyz, 
trigger=node_lost_trigger, stage=[STARTED, ABORTED, SUCCEEDED], 
beforeAction=execute_plan, 
class=org.apache.solr.cloud.autoscaling.AutoScaling$HttpTriggerListener, 
url=http://xyz.com/on_node_lost?node={$LOST_NODE_NAME}}, errorMessages=[A 
trigger with the name node_lost_trigger does not exist]}], 
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
   [junit4]   2>        at 
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
   [junit4]   2>        at 
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2>        at 
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 891510 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 891510 INFO  
(TEST-AutoScalingHandlerTest.test-seed#[992133C0B38D6916]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 891520 INFO  
(TEST-AutoScalingHandlerTest.testConcurrentUpdates-seed#[992133C0B38D6916]) [   
 ] o.a.s.SolrTestCaseJ4 ###Starting testConcurrentUpdates
   [junit4]   2> 891523 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_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> 891523 INFO  (qtp26578262-8766) [n:127.0.0.1:40527_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> 891524 INFO  (qtp22235827-8685) [n:127.0.0.1:46779_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> 891525 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891525 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891526 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891526 INFO  (qtp26578262-8686) [n:127.0.0.1:40527_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> 891527 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891527 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891528 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891529 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_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> 891529 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891529 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891530 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 891530 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891532 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_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> 891532 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891532 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891532 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_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> 891533 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891534 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891535 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_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> 891535 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891535 INFO  (qtp26578262-8684) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891536 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891537 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891537 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891537 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891538 INFO  (qtp22235827-8769) [n:127.0.0.1:46779_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> 891538 INFO  (qtp26578262-8686) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891539 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891539 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891540 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_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> 891540 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891541 INFO  (qtp26578262-8766) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 891542 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891542 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891543 INFO  (qtp22235827-8687) [n:127.0.0.1:46779_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> 891543 INFO  (qtp22235827-8695) [n:127.0.0.1:46779_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> 891543 INFO  (qtp26578262-8692) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891545 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891545 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891545 INFO  (qtp22235827-8769) [n:127.0.0.1:46779_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> 891546 INFO  (qtp22235827-8693) [n:127.0.0.1:46779_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> 891546 INFO  (qtp26578262-8682) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 891547 INFO  (qtp26578262-8694) [n:127.0.0.1:40527_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> 891547 INFO  (qtp26578262-8683) [n:127.0.0.1:40527_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> 891548 INFO  (qtp22235827-8688) [n:127.0.0.1:46779_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> 891548 INFO  (qtp22235827-8690) [n:127.0.0.1:46779_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> 891549 INFO  (qtp26578262-8686) [n:127.0.0.1:40527_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 891550 INFO  (qtp26578262-8696) [n:127.0.0.1:40527_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> 891550 INFO  (qtp26578262-8694) [n:127.0.

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

olr) [n:127.0.0.1:46705_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] 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> 2568699 INFO  
(zkCallback-3351-thread-2-processing-n:127.0.0.1:46705_solr) 
[n:127.0.0.1:46705_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1]  
CLOSING SolrCore org.apache.solr.core.SolrCore@17ed1eb
   [junit4]   2> 2568699 INFO  
(zkCallback-3351-thread-2-processing-n:127.0.0.1:46705_solr) 
[n:127.0.0.1:46705_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core..system.shard1.replica_n1, tag=25088491
   [junit4]   2> 2568700 INFO  
(zkCallback-3351-thread-2-processing-n:127.0.0.1:46705_solr) 
[n:127.0.0.1:46705_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@a85906: rootName = 
solr_46705, domain = solr.core..system.shard1.replica_n1, service url = null, 
agent id = null] for registry solr.core..system.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@895930
   [junit4]   2> 2568709 INFO  
(zkCallback-3351-thread-2-processing-n:127.0.0.1:46705_solr) 
[n:127.0.0.1:46705_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.collection..system.shard1.leader, tag=25088491
   [junit4]   2> 2568710 INFO  (jetty-closer-3326-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@19221f0{/solr,null,UNAVAILABLE}
   [junit4]   2> 2569737 WARN  
(zkCallback-3371-thread-1-processing-n:127.0.0.1:40115_solr) 
[n:127.0.0.1:40115_solr    ] o.a.s.c.ZkController Unable to register nodeLost 
path for 127.0.0.1:40115_solr
   [junit4]   2> org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:40115_solr
   [junit4]   2>        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]   2>        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]   2>        at 
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:364)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:364)
   [junit4]   2>        at 
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:879)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:704)
   [junit4]   2>        at 
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:703)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader.access$900(ZkStateReader.java:72)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1090)
   [junit4]   2>        at 
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1085)
   [junit4]   2>        at 
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:268)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 2569737 INFO  (jetty-closer-3326-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1414514{/solr,null,UNAVAILABLE}
   [junit4]   2> 2569738 ERROR 
(SUITE-TestClusterStateProvider-seed#[992133C0B38D6916]-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> 2569738 INFO  
(SUITE-TestClusterStateProvider-seed#[992133C0B38D6916]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34665 34665
   [junit4]   2> 2574871 INFO  (Thread-4195) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:34665 34665
   [junit4]   2> 2574872 WARN  (Thread-4195) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        3       /solr/configs/.system
   [junit4]   2>        3       /solr/configs/.system/managed-schema
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        25      /solr/collections/.system/state.json
   [junit4]   2>        5       /solr/clusterstate.json
   [junit4]   2>        5       /solr/clusterprops.json
   [junit4]   2>        3       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/live_nodes
   [junit4]   2>        5       /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-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.sim.TestClusterStateProvider_992133C0B38D6916-001
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=RandomSimilarity(queryNorm=true): {}, locale=und, timezone=America/Maceio
   [junit4]   2> NOTE: Linux 4.13.0-26-generic i386/Oracle Corporation 
1.8.0_144 (32-bit)/cpus=8,threads=1,free=168669216,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [TestRandomDVFaceting, 
RestartWhileUpdatingTest, BasicZkTest, DeleteInactiveReplicaTest, 
TestSystemCollAutoCreate, TestManagedSchemaAPI, 
TestPHPSerializedResponseWriter, TestClusterProperties, SolrPluginUtilsTest, 
SolrCoreTest, TermVectorComponentTest, BasicDistributedZkTest, 
TestFoldingMultitermQuery, TestFastOutputStream, ResponseLogComponentTest, 
TestPostingsSolrHighlighter, TestSchemaNameResource, 
TestCloudPseudoReturnFields, DirectUpdateHandlerOptimizeTest, 
RAMDirectoryFactoryTest, TestManagedStopFilterFactory, 
CoreMergeIndexesAdminHandlerTest, SearchRateTriggerTest, 
CdcrReplicationDistributedZkTest, MoveReplicaTest, QueryParsingTest, 
TestCodecSupport, TestChildDocTransformer, TestScoreJoinQPNoScore, 
TestDistribStateManager, JvmMetricsTest, HLLUtilTest, 
TestSuggestSpellingConverter, SpatialHeatmapFacetsTest, EnumFieldTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSimpleTextCodec, 
PeerSyncTest, TestFreeTextSuggestions, TestMissingGroups, QueryResultKeyTest, 
LeaderFailoverAfterPartitionTest, TestNumericRangeQuery64, 
SimpleCollectionCreateDeleteTest, SolrInfoBeanTest, TestSchemaVersionResource, 
OverseerTaskQueueTest, CurrencyFieldTypeTest, TestObjectReleaseTracker, 
TestOnReconnectListenerSupport, UpdateRequestProcessorFactoryTest, 
DocumentBuilderTest, StatelessScriptUpdateProcessorFactoryTest, TestNRTOpen, 
BlockJoinFacetDistribTest, TestBadConfig, CachingDirectoryFactoryTest, 
TestPolicyCloud, TestCSVLoader, BigEndianAscendingWordDeserializerTest, 
SearchHandlerTest, DistributedQueryComponentOptimizationTest, 
LeaderElectionIntegrationTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
SliceStateTest, ZkStateWriterTest, TestImplicitCoreProperties, 
SimpleFacetsTest, NoCacheHeaderTest, TestDFISimilarityFactory, 
SaslZkACLProviderTest, TestAuthorizationFramework, DistanceUnitsTest, 
TestRecovery, TestLegacyTerms, TestOrdValues, TestPhraseSuggestions, 
MergeStrategyTest, HdfsLockFactoryTest, UninvertDocValuesMergePolicyTest, 
TestConfigSetImmutable, AssignBackwardCompatibilityTest, StatsReloadRaceTest, 
TriggerIntegrationTest, TestInPlaceUpdatesDistrib, TestNumericTerms32, 
DateMathParserTest, InfixSuggestersTest, TestXmlQParser, 
TestQueryWrapperFilter, CollectionStateFormat2Test, 
TestLocalFSCloudBackupRestore, DeleteReplicaTest, TestUpdate, 
TestReversedWildcardFilterFactory, ExplicitHLLTest, TestLargeCluster, 
TestUtilizeNode, AnalysisAfterCoreReloadTest, CurrencyRangeFacetCloudTest, 
TestEmbeddedSolrServerSchemaAPI, DistribCursorPagingTest, SmileWriterTest, 
TestJsonFacetRefinement, FileUtilsTest, TimeRoutedAliasUpdateProcessorTest, 
TestCloudInspectUtil, TestBinaryField, 
TestDistributedStatsComponentCardinality, TestSolrIndexConfig, TestUtils, 
SolrMetricManagerTest, HdfsNNFailoverTest, DistributedMLTComponentTest, 
TestInitQParser, TestOverriddenPrefixQueryForCustomFieldType, 
TestPullReplicaErrorHandling, SpellCheckCollatorTest, SearchHandlerTest, 
DistributedQueryElevationComponentTest, TestSolrCloudSnapshots, 
BasicFunctionalityTest, TestRawTransformer, JsonLoaderTest, 
TestReplicationHandler, TestExportWriter, AsyncCallRequestStatusResponseTest, 
CollectionsAPIAsyncDistributedZkTest, DeleteShardTest, 
DistribJoinFromCollectionTest, ForceLeaderTest, NodeMutatorTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, 
OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, 
RemoteQueryErrorTest, RollingRestartTest, ShardSplitTest, 
TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, 
ZkFailoverTest, ExecutePlanActionTest, TestClusterStateProvider]
   [junit4] Completed [582/767 (2!)] on J0 in 15.33s, 2 tests, 1 failure <<< 
FAILURES!

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

Reply via email to