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 2021/02/11 10:48:51 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 1565 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/1565/
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([F68C7D2395DA400D:A1A586964E28A216]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:633)
at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:920)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testSuggestionsWithPayload
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([F68C7D2395DA400D:AF40702EC992E952]:0)
at org.junit.Assert.fail(Assert.java:87)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.junit.Assert.assertTrue(Assert.java:53)
at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testSuggestionsWithPayload(AutoScalingHandlerTest.java:134)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14168 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> 729127 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 729127 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/data-dir-79-001
[junit4] 2> 729128 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 729129 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 729129 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 729129 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 729130 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001
[junit4] 2> 729130 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 729130 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 729130 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 729132 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 729230 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 40961
[junit4] 2> 729230 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:40961
[junit4] 2> 729230 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40961
[junit4] 2> 729230 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40961
[junit4] 2> 729232 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729234 INFO (zkConnectionManagerCallback-8644-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729234 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 729236 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729237 INFO (zkConnectionManagerCallback-8646-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729237 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 729238 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729239 INFO (zkConnectionManagerCallback-8648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729239 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 729342 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 729343 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 729343 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 729343 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 729347 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@278a464d{/solr,null,AVAILABLE}
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b742cb{/solr,null,AVAILABLE}
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5085b157{SSL, (ssl, http/1.1)}{127.0.0.1:37089}
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.e.j.s.Server Started @729383ms
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40961/solr, hostPort=37089}
[junit4] 2> 729348 ERROR (jetty-launcher-8649-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2f90233a{SSL, (ssl, http/1.1)}{127.0.0.1:34035}
[junit4] 2> 729348 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-11T10:01:53.307Z
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.e.j.s.Server Started @729383ms
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40961/solr, hostPort=34035}
[junit4] 2> 729349 ERROR (jetty-launcher-8649-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 729349 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-11T10:01:53.308Z
[junit4] 2> 729350 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729350 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729351 INFO (zkConnectionManagerCallback-8651-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729351 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 729351 INFO (zkConnectionManagerCallback-8653-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729351 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 729351 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 729352 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 729356 WARN (jetty-launcher-8649-thread-1-SendThread(127.0.0.1:40961)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10347775bf80003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 729356 WARN (jetty-launcher-8649-thread-2-SendThread(127.0.0.1:40961)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10347775bf80004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 729966 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 729967 WARN (jetty-launcher-8649-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 729968 WARN (jetty-launcher-8649-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4ad23543[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 729968 WARN (jetty-launcher-8649-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4ad23543[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 729973 WARN (jetty-launcher-8649-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 729974 WARN (jetty-launcher-8649-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c02d169[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 729974 WARN (jetty-launcher-8649-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c02d169[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 729975 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40961/solr
[junit4] 2> 729979 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 729981 INFO (zkConnectionManagerCallback-8667-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 729981 INFO (jetty-launcher-8649-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 730091 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 730095 INFO (zkConnectionManagerCallback-8669-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 730095 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 730148 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37089_solr
[junit4] 2> 730148 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.Overseer Overseer (id=72980596937719814-127.0.0.1:37089_solr-n_0000000000) starting
[junit4] 2> 730155 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37089_solr
[junit4] 2> 730156 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37089_solr
[junit4] 2> 730156 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 730158 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 730158 WARN (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_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> 730179 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 730180 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 730181 WARN (jetty-launcher-8649-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 730187 WARN (jetty-launcher-8649-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d3baebf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 730187 WARN (jetty-launcher-8649-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d3baebf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 730190 WARN (jetty-launcher-8649-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 730195 WARN (jetty-launcher-8649-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2f98c636[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 730195 WARN (jetty-launcher-8649-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2f98c636[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 730196 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40961/solr
[junit4] 2> 730197 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 730199 INFO (zkConnectionManagerCallback-8688-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 730199 INFO (jetty-launcher-8649-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 730209 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730216 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730217 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730218 INFO (jetty-launcher-8649-thread-1) [n:127.0.0.1:37089_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1
[junit4] 2> 730301 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 730302 INFO (zkConnectionManagerCallback-8691-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 730302 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 730306 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 730309 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34035_solr as DOWN
[junit4] 2> 730310 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 730310 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34035_solr
[junit4] 2> 730311 INFO (zkCallback-8668-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 730312 INFO (zkCallback-8690-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 730312 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 730313 WARN (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_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> 730324 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 730343 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34035.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730353 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34035.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730353 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34035.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730354 INFO (jetty-launcher-8649-thread-2) [n:127.0.0.1:34035_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node2
[junit4] 2> 730370 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 730371 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 730375 INFO (zkConnectionManagerCallback-8704-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 730375 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 730376 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 730377 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40961/solr ready
[junit4] 2> 730395 INFO (TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[F68C7D2395DA400D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteUsedPolicy
[junit4] 2> 730488 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 730495 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 730496 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 730498 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=64
[junit4] 2> 730507 INFO (OverseerThreadFactory-8676-thread-1-processing-n:127.0.0.1:37089_solr) [n:127.0.0.1:37089_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection COLL1
[junit4] 2> 730631 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 730633 INFO (qtp528434931-13802) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 730645 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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> "node_name":"127.0.0.1:37089_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 730849 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730851 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 730860 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 730867 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 730868 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730868 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730868 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.core.COLL1.shard1.replica_n1' (registry 'solr.core.COLL1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 730868 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/COLL1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/COLL1_shard1_replica_n1/data/]
[junit4] 2> 730913 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730913 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730914 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 730914 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 730916 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730916 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730917 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 730917 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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 1691392634913816576
[junit4] 2> 730919 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 730920 INFO (searcherExecutor-8706-thread-1-processing-n:127.0.0.1:37089_solr x:COLL1_shard1_replica_n1 c:COLL1 s:shard1 r:core_node2) [n:127.0.0.1:37089_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 autowarm time: 0 ms
[junit4] 2> 730923 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730923 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730927 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730927 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730927 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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:37089/solr/COLL1_shard1_replica_n1/
[junit4] 2> 730928 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 730928 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:37089/solr/COLL1_shard1_replica_n1/ has no replicas
[junit4] 2> 730928 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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/72980596937719814-core_node2-n_0000000000
[junit4] 2> 730930 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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:37089/solr/COLL1_shard1_replica_n1/ shard1
[junit4] 2> 730931 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731032 INFO (zkCallback-8668-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> 731032 INFO (zkCallback-8668-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> 731032 INFO (zkCallback-8668-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> 731034 INFO (qtp528434931-13804) [n:127.0.0.1:37089_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> 731036 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731038 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr ] 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=189
[junit4] 2> 731039 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_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> 731039 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731137 INFO (zkCallback-8690-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> 731137 INFO (zkCallback-8668-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> 731137 INFO (zkCallback-8668-thread-4) [ ] 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> 731137 INFO (zkCallback-8668-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> 731137 INFO (zkCallback-8668-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> 731138 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_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=633
[junit4] 2> 731140 ERROR (qtp1617611644-13808) [n:127.0.0.1:34035_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:246)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:181)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:269)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:340)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[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:1612)
[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:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[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:1349)
[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:716)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[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:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395)
[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:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 731141 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=2
[junit4] 2> 731144 INFO (OverseerCollectionConfigSetProcessor-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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> 731144 INFO (OverseerThreadFactory-8676-thread-2-processing-n:127.0.0.1:37089_solr) [n:127.0.0.1:37089_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 731145 INFO (qtp528434931-13799) [n:127.0.0.1:37089_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> 731145 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr x:COLL1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3950c4d8: rootName = solr_37089, 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@73535065
[junit4] 2> 731164 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.c.SolrCore [COLL1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@cfbad03
[junit4] 2> 731164 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.COLL1.shard1.replica_n1 tag=SolrCore@cfbad03
[junit4] 2> 731164 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.COLL1.shard1.leader tag=SolrCore@cfbad03
[junit4] 2> 731165 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 731166 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/COLL1/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 731167 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] 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=22
[junit4] 2> 731168 INFO (OverseerThreadFactory-8676-thread-2-processing-n:127.0.0.1:37089_solr) [n:127.0.0.1:37089_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731270 INFO (zkCallback-8668-thread-4) [ ] 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> 731270 INFO (zkCallback-8668-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> 731270 INFO (zkCallback-8668-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> 731270 INFO (zkCallback-8668-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> 731283 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=COLL1&action=DELETE&wt=javabin&version=2} status=0 QTime=141
[junit4] 2> 731284 INFO (TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[F68C7D2395DA400D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteUsedPolicy
[junit4] 2> 731301 INFO (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[F68C7D2395DA400D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
[junit4] 2> 731303 ERROR (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{set-policy={xyz=[{replica=<2, shard=#EACH, node=#ANY}, {nodeRole=!overseer, replica=0}], policy1=[{cores=<2, node=#ANY}, {replica=<2, shard=#EACH, node=#ANY}]}, errorMessages=[cores is only allowed in 'cluster-policy']}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:246)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:181)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:269)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:340)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[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:1612)
[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:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[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:1349)
[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:716)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[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:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395)
[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:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 731303 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 731313 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731320 INFO (qtp528434931-13802) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731321 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 731324 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731325 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731326 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 731328 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731329 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731330 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 731333 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731334 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731334 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731336 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 731337 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 731338 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731342 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731343 INFO (qtp528434931-13802) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731344 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731348 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731349 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.cores,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731350 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 731350 INFO (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[F68C7D2395DA400D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
[junit4] 2> 731367 INFO (TEST-AutoScalingHandlerTest.testReadApi-seed#[F68C7D2395DA400D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 731394 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731395 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731396 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=28
[junit4] 2> 731398 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731399 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 731400 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731404 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=1
[junit4] 2> 731405 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731406 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 731408 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731409 INFO (qtp528434931-13802) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731409 INFO (qtp1617611644-13811) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731412 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 731414 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731416 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731416 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 731420 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731422 INFO (qtp528434931-13799) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731423 INFO (qtp1617611644-13808) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 731431 INFO (OverseerThreadFactory-8676-thread-3-processing-n:127.0.0.1:37089_solr) [n:127.0.0.1:37089_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection readApiTestViolations
[junit4] 2> 731432 INFO (OverseerCollectionConfigSetProcessor-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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> 731637 WARN (OverseerThreadFactory-8676-thread-3-processing-n:127.0.0.1:37089_solr) [n:127.0.0.1:37089_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> 731641 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731643 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node} status=0 QTime=0
[junit4] 2> 731659 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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> "node_name":"127.0.0.1:34035_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731662 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37089_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731665 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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_n5",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:34035_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731668 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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_n7",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37089_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731671 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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_n9",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:34035_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731673 INFO (OverseerStateUpdate-72980596937719814-127.0.0.1:37089_solr-n_0000000000) [n:127.0.0.1:37089_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_n11",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37089_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 731876 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr x:readApiTestViolations_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731879 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731879 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr x:readApiTestViolations_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731891 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr x:readApiTestViolations_shard1_replica_n5 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731892 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr x:readApiTestViolations_shard1_replica_n7 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n7&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731893 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731894 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731898 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr x:readApiTestViolations_shard1_replica_n9 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n9&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731899 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr x:readApiTestViolations_shard1_replica_n11 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n11&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 731900 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731901 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731906 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 731911 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731916 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731938 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731940 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731941 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731941 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731941 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 731941 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34035.solr.core.readApiTestViolations.shard1.replica_n1' (registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 731942 INFO (qtp1617611644-13807) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1 ] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 731947 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731948 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731948 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 731948 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731948 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n3' using configuration from configset conf, trusted=true
[junit4] 2> 731948 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.core.readApiTestViolations.shard1.replica_n3' (registry 'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 731948 INFO (qtp528434931-13803) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3 ] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n3/data/]
[junit4] 2> 731955 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731955 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731955 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n9' using configuration from configset conf, trusted=true
[junit4] 2> 731956 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34035.solr.core.readApiTestViolations.shard1.replica_n9' (registry 'solr.core.readApiTestViolations.shard1.replica_n9') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 731956 INFO (qtp1617611644-13809) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9 ] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n9/data/]
[junit4] 2> 731956 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731957 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731957 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n7' using configuration from configset conf, trusted=true
[junit4] 2> 731962 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.core.readApiTestViolations.shard1.replica_n7' (registry 'solr.core.readApiTestViolations.shard1.replica_n7') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 731962 INFO (qtp528434931-13800) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7 ] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n7/data/]
[junit4] 2> 731963 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731963 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 731964 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731964 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 731964 INFO (qtp1617611644-13810) [n:127.0.0.1:34035_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n5' using configuration from configset conf, trusted=true
[junit4] 2> 731964 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n11' using configuration from configset conf, trusted=true
[junit4] 2> 731964 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37089.solr.core.readApiTestViolations.shard1.replica_n11' (registry 'solr.core.readApiTestViolations.shard1.replica_n11') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dcfb2eb
[junit4] 2> 731964 INFO (qtp528434931-13804) [n:127.0.0.1:37089_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11 ] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001/tempDir-001/node1/readApiTestViolations_shard1_replic
[...truncated too long message...]
e.metrics.MetricRegistry@17b7fac0
[junit4] 2> 741118 INFO (closeThreadPool-8817-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72980596937719814-127.0.0.1:37089_solr-n_0000000000) closing
[junit4] 2> 741118 INFO (zkCallback-8690-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 741129 INFO (jetty-closer-8801-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 741129 INFO (jetty-closer-8801-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2644e87c: rootName = solr_34035, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5db910af
[junit4] 2> 741132 INFO (jetty-closer-8801-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 741132 INFO (jetty-closer-8801-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7e58bcf9: rootName = solr_34035, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@440fa72e
[junit4] 2> 741132 INFO (jetty-closer-8801-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 741140 WARN (jetty-launcher-8649-thread-2-SendThread(127.0.0.1:40961)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10347775bf80008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 741197 INFO (jetty-closer-8801-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c3b19fb{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 741198 INFO (jetty-closer-8801-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1760acf{/solr,null,STOPPED}
[junit4] 2> 741198 INFO (jetty-closer-8801-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 741297 INFO (jetty-closer-8801-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2f90233a{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 741298 INFO (jetty-closer-8801-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b742cb{/solr,null,STOPPED}
[junit4] 2> 741298 INFO (jetty-closer-8801-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 741397 INFO (jetty-closer-8801-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72980596937719814-127.0.0.1:37089_solr-n_0000000000) closing
[junit4] 2> 741398 INFO (jetty-closer-8801-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5085b157{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 741398 INFO (jetty-closer-8801-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@278a464d{/solr,null,STOPPED}
[junit4] 2> 741398 INFO (jetty-closer-8801-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 741399 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 741501 WARN (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker-SendThread(127.0.0.1:40961)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10347775bf80001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10347775bf80001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 741602 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> 20 /solr/collections/readApiTestViolations/terms/shard1
[junit4] 2> 8 /solr/collections/testDiagnosticsWithPayload.COLL/state.json
[junit4] 2> 8 /solr/collections/testDiagnosticsWithPayload.COLL/terms/shard1
[junit4] 2> 5 /solr/collections/testSuggestionsWithPayload.COLL/terms/shard1
[junit4] 2> 4 /solr/configs/conf
[junit4] 2> 4 /solr/collections/COLL1/state.json
[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> 3 /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> 62 /solr/collections/readApiTestViolations/state.json
[junit4] 2> 24 /solr/collections/testSuggestionsWithPayload.COLL/state.json
[junit4] 2> 23 /solr/collections/testDiagnosticsWithPayload.COLL/state.json
[junit4] 2> 13 /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/72980596937719816-core_node2-n_0000000000
[junit4] 2> 2 /solr/collections/readApiTestViolations/leader_elect/shard1/election/72980596937719816-core_node10-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 87 /solr/overseer/queue
[junit4] 2> 25 /solr/collections
[junit4] 2> 23 /solr/overseer/collection-queue-work
[junit4] 2> 19 /solr/collections/readApiTestViolations/state.json
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 10 /solr/collections/testDiagnosticsWithPayload.COLL/state.json
[junit4] 2> 9 /solr/collections/testSuggestionsWithPayload.COLL/state.json
[junit4] 2> 5 /solr/collections/COLL1/state.json
[junit4] 2>
[junit4] 2> 741605 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:40961
[junit4] 2> 741605 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40961
[junit4] 2> 741605 INFO (SUITE-AutoScalingHandlerTest-seed#[F68C7D2395DA400D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40961
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_F68C7D2395DA400D-001
[junit4] 2> Feb 11, 2021 10:02:05 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-DO, timezone=Asia/Saigon
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=199022176,total=523763712
[junit4] 2> NOTE: All tests run in this JVM: [MetricUtilsTest, TestHighFrequencyDictionaryFactory, HighlighterWithoutStoredIdTest, SolrIndexSplitterTest, TestBlendedInfixSuggestions, CdcrVersionReplicationTest, TestManagedSchema, NumericFieldsTest, TestConfigOverlay, TestSolrConfigHandlerCloud, DocValuesNotIndexedTest, TestTermsQParserPlugin, AssignBackwardCompatibilityTest, RankQueryTest, TestXmlQParserPlugin, ChaosMonkeyNothingIsSafeTest, TestSolrXml, BasicAuthOnSingleNodeTest, ShardRoutingTest, TimeRoutedAliasUpdateProcessorTest, SolrRrdBackendFactoryTest, TestSchemaSimilarityResource, TestJoin, TestStressLiveNodes, SchemaApiFailureTest, UpdateLogTest, TestSimUtils, TestCollectionAPI, TestSuggestSpellingConverter, TestPullReplicaErrorHandling, NestedShardedAtomicUpdateTest, CircularListTest, IndexSchemaRuntimeFieldTest, TestMergePolicyConfig, TestNumericTerms32, BufferStoreTest, ResourceLoaderTest, TestNumericRangeQuery64, TestIndexSearcher, TestCoreContainer, DistribDocExpirationUpdateProcessorTest, PeerSyncWithIndexFingerprintCachingTest, TimeZoneUtilsTest, MoveReplicaHDFSTest, ByteBuffersDirectoryFactoryTest, BJQParserTest, ResponseBuilderTest, TestManagedResourceStorage, OverseerSolrResponseUnsafeSerializationTest, DistributedFacetPivotLongTailTest, ScheduledMaintenanceTriggerTest, SpellCheckComponentTest, ZkNodePropsTest, StressHdfsTest, SolrMetricReporterTest, AutoScalingHandlerTest]
[junit4] Completed [275/924 (1!)] on J1 in 13.52s, 12 tests, 2 failures <<< FAILURES!
[...truncated 52994 lines...]