You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/01/26 20:18:29 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-master - Build # 603 -
Unstable
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-master/603/
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi
Error Message:
expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<2> but was:<3>
at __randomizedtesting.SeedInfo.seed([7AAB9838F557161D:2D82638D2EA5F406]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:898)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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 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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testSuggestionsWithPayload
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([7AAB9838F557161D:23679535A91FBF42]:0)
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertTrue(Assert.java:52)
at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testSuggestionsWithPayload(AutoScalingHandlerTest.java:129)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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 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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 13333 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> 425449 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/data-dir-64-001
[junit4] 2> 425449 WARN (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 425449 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 425450 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 425451 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 425451 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/tempDir-001
[junit4] 2> 425451 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 425452 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 425452 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 425552 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37751
[junit4] 2> 425552 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37751
[junit4] 2> 425552 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37751
[junit4] 2> 425552 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37751
[junit4] 2> 425554 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 425556 INFO (zkConnectionManagerCallback-2476-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 425556 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 425558 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 425559 INFO (zkConnectionManagerCallback-2478-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 425559 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 425559 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 425561 INFO (zkConnectionManagerCallback-2480-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 425561 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 425668 WARN (jetty-launcher-2481-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 425669 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 425669 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 425669 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 425671 WARN (jetty-launcher-2481-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 425671 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 425671 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 425671 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e0cb04{/solr,null,AVAILABLE}
[junit4] 2> 425673 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c766826{/solr,null,AVAILABLE}
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@74b12180{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:37765}
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@25d8d213{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:35199}
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-1) [ ] o.e.j.s.Server Started @425711ms
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-2) [ ] o.e.j.s.Server Started @425711ms
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37765}
[junit4] 2> 425674 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35199}
[junit4] 2> 425675 ERROR (jetty-launcher-2481-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 425675 ERROR (jetty-launcher-2481-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-26T19:13:30.821192Z
[junit4] 2> 425675 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-26T19:13:30.821196Z
[junit4] 2> 425676 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 425676 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 425677 INFO (zkConnectionManagerCallback-2483-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 425677 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 425677 INFO (zkConnectionManagerCallback-2485-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 425677 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 425678 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 425678 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 426237 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 426238 WARN (jetty-launcher-2481-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a56814[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 426238 WARN (jetty-launcher-2481-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a56814[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 426241 WARN (jetty-launcher-2481-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@31111629[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 426241 WARN (jetty-launcher-2481-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@31111629[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 426242 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37751/solr
[junit4] 2> 426243 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 426244 INFO (zkConnectionManagerCallback-2493-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 426244 INFO (jetty-launcher-2481-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 426346 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 426347 INFO (zkConnectionManagerCallback-2495-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 426347 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 426435 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35199_solr
[junit4] 2> 426436 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.Overseer Overseer (id=72178281608642566-127.0.0.1:35199_solr-n_0000000000) starting
[junit4] 2> 426441 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35199_solr
[junit4] 2> 426441 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35199_solr
[junit4] 2> 426442 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 426443 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 426444 WARN (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 426467 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 426499 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35199.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 426510 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35199.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 426510 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35199.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 426511 INFO (jetty-launcher-2481-thread-2) [n:127.0.0.1:35199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/tempDir-001/node2/.
[junit4] 2> 427443 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 427444 WARN (jetty-launcher-2481-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c772971[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427444 WARN (jetty-launcher-2481-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c772971[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427448 WARN (jetty-launcher-2481-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3eb2fe08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427448 WARN (jetty-launcher-2481-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3eb2fe08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427449 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37751/solr
[junit4] 2> 427449 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 427451 INFO (zkConnectionManagerCallback-2505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 427451 INFO (jetty-launcher-2481-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 427553 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 427554 INFO (zkConnectionManagerCallback-2507-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 427554 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 427558 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 427562 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37765_solr as DOWN
[junit4] 2> 427562 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 427562 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37765_solr
[junit4] 2> 427564 INFO (zkCallback-2494-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 427564 INFO (zkCallback-2506-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 427565 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 427565 WARN (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 427588 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 427615 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37765.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 427627 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37765.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 427627 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37765.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 427629 INFO (jetty-launcher-2481-thread-1) [n:127.0.0.1:37765_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/tempDir-001/node1/.
[junit4] 2> 427661 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 427662 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 427663 INFO (zkConnectionManagerCallback-2516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 427663 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 427664 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 427665 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37751/solr ready
[junit4] 2> 427686 INFO (TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteUsedPolicy
[junit4] 2> 427733 INFO (qtp790673763-7020) [n:127.0.0.1:35199_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> 427763 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_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> 427764 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 427765 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete and sendToOCPQueue=true
[junit4] 2> 427768 INFO (OverseerThreadFactory-2650-thread-1-processing-n:127.0.0.1:35199_solr) [n:127.0.0.1:35199_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection COLL1
[junit4] 2> 427878 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 427880 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 427901 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_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":"https://127.0.0.1:37765/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 428104 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] 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> 429115 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 429124 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429126 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429126 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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 configset conf, trusted=true
[junit4] 2> 429127 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37765.solr.core.COLL1.shard1.replica_n1' (registry 'solr.core.COLL1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@235b8a9
[junit4] 2> 429127 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/tempDir-001/node1/COLL1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001/tempDir-001/node1/./COLL1_shard1_replica_n1/data/]
[junit4] 2> 429215 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429215 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429217 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 429217 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 429218 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@35b4bee2[COLL1_shard1_replica_n1] main]
[junit4] 2> 429220 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429220 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429221 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429221 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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 1656819301080891392
[junit4] 2> 429224 INFO (searcherExecutor-2662-thread-1-processing-n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 c:COLL1 s:shard1 r:core_node2) [n:127.0.0.1:37765_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@35b4bee2[COLL1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 429227 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/COLL1/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 429227 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/COLL1/leaders/shard1
[junit4] 2> 429230 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429230 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429230 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37765/solr/COLL1_shard1_replica_n1/
[junit4] 2> 429231 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429231 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:37765/solr/COLL1_shard1_replica_n1/ has no replicas
[junit4] 2> 429231 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/COLL1/leaders/shard1/leader after winning as /collections/COLL1/leader_elect/shard1/election/72178281608642568-core_node2-n_0000000000
[junit4] 2> 429233 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37765/solr/COLL1_shard1_replica_n1/ shard1
[junit4] 2> 429344 INFO (zkCallback-2506-thread-1) [ ] 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> 429345 INFO (zkCallback-2506-thread-2) [ ] 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> 429346 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429348 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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=1243
[junit4] 2> 429350 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 429448 INFO (zkCallback-2494-thread-1) [ ] 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> 429448 INFO (zkCallback-2506-thread-2) [ ] 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> 429448 INFO (zkCallback-2506-thread-1) [ ] 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> 429448 INFO (zkCallback-2506-thread-3) [ ] 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> 429449 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete} status=0 QTime=1683
[junit4] 2> 429452 ERROR (qtp790673763-7016) [n:127.0.0.1:35199_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:241)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:178)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:842)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:808)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:559)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 429452 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=400 QTime=2
[junit4] 2> 429453 INFO (qtp790673763-7020) [n:127.0.0.1:35199_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> 429456 INFO (OverseerCollectionConfigSetProcessor-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_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> 429456 INFO (OverseerThreadFactory-2650-thread-2-processing-n:127.0.0.1:35199_solr) [n:127.0.0.1:35199_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 429458 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.COLL1.shard1.replica_n1, tag=null
[junit4] 2> 429458 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a448c27: rootName = solr_37765, 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@694f352e
[junit4] 2> 429486 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.c.SolrCore [COLL1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@434f78ad
[junit4] 2> 429486 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.COLL1.shard1.replica_n1, tag=SolrCore@434f78ad
[junit4] 2> 429486 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.COLL1.shard1.leader, tag=SolrCore@434f78ad
[junit4] 2> 429487 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 429490 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/COLL1/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 429491 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr x:COLL1_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=COLL1_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=33
[junit4] 2> 429594 INFO (zkCallback-2494-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/COLL1/state.json] for collection [COLL1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 429594 INFO (zkCallback-2494-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/COLL1/state.json] for collection [COLL1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 429594 INFO (zkCallback-2506-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/COLL1/state.json] for collection [COLL1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 429594 INFO (zkCallback-2506-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/COLL1/state.json] for collection [COLL1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 429594 INFO (zkCallback-2506-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/COLL1/state.json] for collection [COLL1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 429601 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=COLL1&action=DELETE&wt=javabin&version=2} status=0 QTime=148
[junit4] 2> 429602 INFO (TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteUsedPolicy
[junit4] 2> 429620 INFO (TEST-AutoScalingHandlerTest.testSetProperties-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSetProperties
[junit4] 2> 429644 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429655 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429656 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=24
[junit4] 2> 429657 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429660 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429661 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429662 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429663 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429665 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429666 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429667 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429668 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429670 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429672 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429673 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429674 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429676 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429677 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429678 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429679 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429680 INFO (TEST-AutoScalingHandlerTest.testSetProperties-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSetProperties
[junit4] 2> 429700 INFO (TEST-AutoScalingHandlerTest.testUpdatePolicy-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdatePolicy
[junit4] 2> 429703 INFO (qtp790673763-7018) [n:127.0.0.1:35199_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> 429704 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_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> 429704 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429706 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429708 INFO (qtp790673763-7018) [n:127.0.0.1:35199_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> 429709 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_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> 429710 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429711 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429712 INFO (TEST-AutoScalingHandlerTest.testUpdatePolicy-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdatePolicy
[junit4] 2> 429729 INFO (TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSuspendTrigger
[junit4] 2> 429730 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429731 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429739 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429740 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429741 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 429747 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429749 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429750 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 429752 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429753 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429754 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429757 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429758 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429759 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429762 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429763 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429764 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429766 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429767 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429768 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429770 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429772 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429772 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429773 WARN (zkCallback-2494-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Error in trigger 'node_lost_trigger' configuration, trigger config ignored: {
[junit4] 2> "event":"nodeLost",
[junit4] 2> "waitFor":600,
[junit4] 2> "enabled":"false",
[junit4] 2> "actions":[
[junit4] 2> {
[junit4] 2> "name":"compute_plan",
[junit4] 2> "class":"solr.ComputePlanAction"},
[junit4] 2> {
[junit4] 2> "name":"execute_plan",
[junit4] 2> "class":"solr.ExecutePlanAction"}],
[junit4] 2> "resumeAt":"1845150499"}
[junit4] 2> => TriggerValidationException{name=node_lost_trigger, details='{resumeAt=unknown property}'}
[junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerBase.configure(TriggerBase.java:119)
[junit4] 2> org.apache.solr.cloud.autoscaling.TriggerValidationException: null
[junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerBase.configure(TriggerBase.java:119) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.NodeLostTrigger.configure(NodeLostTrigger.java:103) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScaling$TriggerFactoryImpl.create(AutoScaling.java:189) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.loadTriggers(OverseerTriggerThread.java:390) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.refreshAutoScalingConf(OverseerTriggerThread.java:327) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread$AutoScalingWatcher.process(OverseerTriggerThread.java:299) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$ProcessWatchWithExecutor.lambda$process$1(SolrZkClient.java:843) ~[java/:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 429778 INFO (TEST-AutoScalingHandlerTest.testSuspendTrigger-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSuspendTrigger
[junit4] 2> 429801 INFO (TEST-AutoScalingHandlerTest.test-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 429805 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429806 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429807 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429809 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429811 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429811 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429816 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429818 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429819 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 429820 ERROR (qtp2118732528-7019) [n:127.0.0.1:37765_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:241)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:178)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:343)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:808)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:559)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 429820 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 429823 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429824 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429824 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429827 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429828 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429829 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429830 ERROR (qtp2118732528-7019) [n:127.0.0.1:37765_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:241)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:178)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:343)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:808)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:559)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 429830 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 429831 INFO (TEST-AutoScalingHandlerTest.test-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 429854 INFO (TEST-AutoScalingHandlerTest.testReadApi-seed#[7AAB9838F557161D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 429857 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429858 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429859 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429861 INFO (qtp790673763-7018) [n:127.0.0.1:35199_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> 429862 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_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> 429863 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 429867 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 429868 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429869 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 429872 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429873 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429874 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429875 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 429879 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429880 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429881 INFO (qtp2118732528-7017) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling/diagnostics params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429885 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429887 INFO (qtp2118732528-7015) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 429888 INFO (qtp790673763-7016) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 429889 INFO (qtp790673763-7018) [n:127.0.0.1:35199_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=3&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 429891 INFO (OverseerThreadFactory-2650-thread-3-processing-n:127.0.0.1:35199_solr) [n:127.0.0.1:35199_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection readApiTestViolations
[junit4] 2> 429892 INFO (OverseerCollectionConfigSetProcessor-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 430096 WARN (OverseerThreadFactory-2650-thread-3-processing-n:127.0.0.1:35199_solr) [n:127.0.0.1:35199_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 6 on collection readApiTestViolations is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 430100 INFO (qtp790673763-7020) [n:127.0.0.1:35199_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 430101 INFO (qtp2118732528-7019) [n:127.0.0.1:37765_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 430109 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35199/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 430111 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:37765/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 430113 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35199/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 430116 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:37765/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 430118 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35199/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 430120 INFO (Over
[...truncated too long message...]
ahale.metrics.MetricRegistry@1ab9a147
[junit4] 2> 442261 INFO (zkCallback-2494-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 442263 INFO (zkCallback-2533-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 442269 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 442269 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24274b5f: rootName = solr_35199, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4458b987
[junit4] 2> 442271 INFO (jetty-closer-2545-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 442271 INFO (jetty-closer-2545-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5bb8b06b: rootName = solr_39349, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4458b987
[junit4] 2> 442273 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 442273 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1de5641: rootName = solr_35199, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4a0c809a
[junit4] 2> 442273 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 442275 INFO (jetty-closer-2545-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 442275 INFO (jetty-closer-2545-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6400b22e: rootName = solr_39349, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4a0c809a
[junit4] 2> 442275 INFO (jetty-closer-2545-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 442287 INFO (closeThreadPool-2556-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72178281608642566-127.0.0.1:35199_solr-n_0000000000) closing
[junit4] 2> 442287 INFO (OverseerStateUpdate-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [n:127.0.0.1:35199_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35199_solr
[junit4] 2> 442290 INFO (OverseerAutoScalingTriggerThread-72178281608642566-127.0.0.1:35199_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 442303 INFO (zkCallback-2533-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 442314 INFO (closeThreadPool-2556-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72178281608642566-127.0.0.1:35199_solr-n_0000000000) closing
[junit4] 2> 442363 INFO (jetty-closer-2545-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@74b12180{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 442363 INFO (jetty-closer-2545-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e0cb04{/solr,null,UNAVAILABLE}
[junit4] 2> 442363 INFO (jetty-closer-2545-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 442462 INFO (jetty-closer-2545-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4aa5a873{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 442463 INFO (jetty-closer-2545-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@69145b03{/solr,null,UNAVAILABLE}
[junit4] 2> 442463 INFO (jetty-closer-2545-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 442562 INFO (jetty-closer-2545-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72178281608642566-127.0.0.1:35199_solr-n_0000000000) closing
[junit4] 2> 442568 INFO (jetty-closer-2545-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@25d8d213{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 442568 INFO (jetty-closer-2545-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c766826{/solr,null,UNAVAILABLE}
[junit4] 2> 442568 INFO (jetty-closer-2545-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 442569 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 442773 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/collections/readApiTestViolations/terms/shard1
[junit4] 2> 7 /solr/collections/testDiagnosticsWithPayload.COLL/terms/shard1
[junit4] 2> 5 /solr/collections/testSuggestionsWithPayload.COLL/terms/shard1
[junit4] 2> 5 /solr/collections/COLL1/state.json
[junit4] 2> 5 /solr/collections/testDiagnosticsWithPayload.COLL/state.json
[junit4] 2> 4 /solr/configs/conf
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/collections/readApiTestViolations/collectionprops.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/collections/COLL1/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 147 /solr/autoscaling.json
[junit4] 2> 64 /solr/collections/readApiTestViolations/state.json
[junit4] 2> 20 /solr/collections/testSuggestionsWithPayload.COLL/state.json
[junit4] 2> 19 /solr/collections/testDiagnosticsWithPayload.COLL/state.json
[junit4] 2> 12 /solr/collections/COLL1/state.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/readApiTestViolations/leader_elect/shard1/election/72178281608642566-core_node7-n_0000000001
[junit4] 2> 2 /solr/overseer_elect/election/72178281608642566-127.0.0.1:35199_solr-n_0000000000
[junit4] 2> 2 /solr/collections/readApiTestViolations/leader_elect/shard1/election/72178281608642566-core_node11-n_0000000004
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 88 /solr/overseer/queue
[junit4] 2> 25 /solr/collections
[junit4] 2> 22 /solr/overseer/collection-queue-work
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 3 /solr/autoscaling/events/node_lost_trigger
[junit4] 2> 2 /solr/autoscaling/events/node_added_trigger
[junit4] 2>
[junit4] 2> 442776 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:37751
[junit4] 2> 442776 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37751
[junit4] 2> 442776 INFO (SUITE-AutoScalingHandlerTest-seed#[7AAB9838F557161D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37751
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_7AAB9838F557161D-001
[junit4] 2> Jan 26, 2020 7:13:47 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=2014, maxMBSortInHeap=5.49618171840395, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@167ec013), locale=brx, timezone=America/North_Dakota/Center
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=282825624,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestLMJelinekMercerSimilarityFactory, TestFieldResource, TestSolrCLIRunExample, ManagedSchemaRoundRobinCloudTest, TestFaceting, TestRandomFlRTGCloud, DistributedQueryComponentCustomSortTest, ShardRoutingTest, TestRestManager, ExternalFileFieldSortTest, HdfsAutoAddReplicasIntegrationTest, XsltUpdateRequestHandlerTest, TestRandomDVFaceting, TestSchemaVersionResource, TestRecovery, TestRequestForwarding, TestCryptoKeys, TestFuzzyAnalyzedSuggestions, SpatialFilterTest, NodeLostTriggerIntegrationTest, SSLMigrationTest, TestSolrCloudWithDelegationTokens, CursorPagingTest, TriLevelCompositeIdRoutingTest, TestPullReplica, TestLegacyTerms, TestCloudDeleteByQuery, CleanupOldIndexTest, ResponseLogComponentTest, TestMacroExpander, TestConfigSetProperties, TestGroupingSearch, ZkCollectionPropsCachingTest, CoreAdminRequestStatusTest, AnalysisAfterCoreReloadTest, TestLegacyField, TestPostingsSolrHighlighter, TestCloudNestedDocsSort, ResolveAnalyzerByNameTest, AnalysisErrorHandlingTest, PhrasesIdentificationComponentTest, URLClassifyProcessorTest, OverseerStatusTest, TestManagedStopFilterFactory, TestJsonFacetRefinement, AnalyticsQueryTest, TestBinaryField, TestAuthorizationFramework, CSVRequestHandlerTest, TestXmlQParserPlugin, ExplicitHLLTest, DistributedFacetPivotLargeTest, TestNumericRangeQuery64, AutoScalingHandlerTest]
[junit4] Completed [116/900 (1!)] on J0 in 17.87s, 12 tests, 2 failures <<< FAILURES!
[...truncated 55158 lines...]