You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@solr.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/03/11 03:02:53 UTC

[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 417 - Unstable!

Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/417/

1 tests failed.
FAILED:  org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory

Error Message:
action did not execute

Stack Trace:
java.lang.AssertionError: action did not execute
	at __randomizedtesting.SeedInfo.seed([65EDBDBBA7D3DFAE:81119461D9B20A9]:0)
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:283)
	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 14595 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
   [junit4]   2> 1054512 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-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.8/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 1054513 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/data-dir-96-001
   [junit4]   2> 1054513 WARN  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1054513 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1054515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1054515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1054515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.SolrCloudTestCase Using per-replica state
   [junit4]   2> 1054516 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/tempDir-001
   [junit4]   2> 1054516 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1054516 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1054516 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1054517 WARN  (ZkTestServer Run Thread) [     ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
   [junit4]   2> 1054616 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer start zk server on port: 34203
   [junit4]   2> 1054616 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34203
   [junit4]   2> 1054616 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34203
   [junit4]   2> 1054616 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34203
   [junit4]   2> 1054617 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1054619 INFO  (zkConnectionManagerCallback-10170-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1054619 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1054621 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1054622 INFO  (zkConnectionManagerCallback-10172-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1054622 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1054623 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1054623 INFO  (zkConnectionManagerCallback-10174-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1054623 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1054726 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1054726 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1054726 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1054726 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1054726 INFO  (jetty-launcher-10175-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> 1054726 INFO  (jetty-launcher-10175-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> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@71debd8a{/solr,null,AVAILABLE}
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1368ec47{/solr,null,AVAILABLE}
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.AbstractConnector Started ServerConnector@2bf7addc{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:43581}
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.e.j.s.Server Started @1054760ms
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:34203/solr, hostPort=43581}
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.AbstractConnector Started ServerConnector@75aa294{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:43465}
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.e.j.s.Server Started @1054760ms
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:34203/solr, hostPort=43465}
   [junit4]   2> 1054727 ERROR (jetty-launcher-10175-thread-1) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1054727 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1054728 ERROR (jetty-launcher-10175-thread-2) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.8.2
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.8.2
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-03-11T02:22:50.363Z
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-03-11T02:22:50.363Z
   [junit4]   2> 1054728 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1054729 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1054729 INFO  (zkConnectionManagerCallback-10177-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1054729 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1054729 INFO  (zkConnectionManagerCallback-10179-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1054729 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1054729 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1054729 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1054734 WARN  (jetty-launcher-10175-thread-2-SendThread(127.0.0.1:34203)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x103d605265e0004.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x103d605265e0004, 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 0x103d605265e0004, 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> 1055263 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1055264 WARN  (jetty-launcher-10175-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7c7d3dd1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055264 WARN  (jetty-launcher-10175-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7c7d3dd1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055266 WARN  (jetty-launcher-10175-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@887aaff[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055267 WARN  (jetty-launcher-10175-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@887aaff[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055268 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34203/solr
   [junit4]   2> 1055269 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1055271 INFO  (zkConnectionManagerCallback-10193-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1055271 INFO  (jetty-launcher-10175-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1055345 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1055346 WARN  (jetty-launcher-10175-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@607b0a41[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055346 WARN  (jetty-launcher-10175-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@607b0a41[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055348 WARN  (jetty-launcher-10175-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@30036b1a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055348 WARN  (jetty-launcher-10175-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@30036b1a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1055349 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34203/solr
   [junit4]   2> 1055349 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1055350 INFO  (zkConnectionManagerCallback-10201-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1055350 INFO  (jetty-launcher-10175-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1055373 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1055373 INFO  (zkConnectionManagerCallback-10203-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1055373 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1055431 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43465_solr
   [junit4]   2> 1055432 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer Overseer (id=73137336574935047-127.0.0.1:43465_solr-n_0000000000) starting
   [junit4]   2> 1055436 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43465_solr
   [junit4]   2> 1055436 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43465_solr
   [junit4]   2> 1055436 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
   [junit4]   2> 1055437 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1055437 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
   [junit4]   2> 1055438 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
   [junit4]   2> 1055438 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
   [junit4]   2> 1055438 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1055438 WARN  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_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> 1055448 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:43465_solr]
   [junit4]   2> 1055449 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
   [junit4]   2> 1055449 DEBUG (ScheduledTrigger-10208-thread-1) [     ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
   [junit4]   2> 1055449 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1055451 DEBUG (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 1055473 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1055474 INFO  (zkConnectionManagerCallback-10216-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1055474 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1055477 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43465.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055478 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1055480 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:43581_solr as DOWN
   [junit4]   2> 1055481 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1055481 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43581_solr
   [junit4]   2> 1055482 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "operation":"downnode"} current state version: 0
   [junit4]   2> 1055482 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:43581_solr
   [junit4]   2> 1055482 INFO  (zkCallback-10202-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1055483 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1055484 WARN  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_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> 1055487 INFO  (zkCallback-10215-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1055490 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43465.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055490 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43465.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055491 INFO  (jetty-launcher-10175-thread-2) [n:127.0.0.1:43465_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/tempDir-001/node2
   [junit4]   2> 1055498 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1055517 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43581.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055525 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43581.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055525 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43581.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055526 INFO  (jetty-launcher-10175-thread-1) [n:127.0.0.1:43581_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/tempDir-001/node1
   [junit4]   2> 1055538 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1055539 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1055540 INFO  (zkConnectionManagerCallback-10230-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1055540 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1055541 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1055542 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34203/solr ready
   [junit4]   2> 1055551 INFO  (OverseerThreadFactory-10210-thread-1-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
   [junit4]   2> 1055553 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "name":".system",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "numShards":1,
   [junit4]   2>   "createNodeSet":"127.0.0.1:43465_solr",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1055554 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
   [junit4]   2> 1055555 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
   [junit4]   2> 1055657 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1055657 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1055658 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1055658 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node2":{
   [junit4]   2>       "core":".system_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43465/solr"}}}
   [junit4]   2> 1055758 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
   [junit4]   2> 1055860 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr    x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1055860 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr    x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1055867 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node2",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1055867 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node2",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1055867 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1055867 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           "core":".system_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"}}}}}
   [junit4]   2> 1055873 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.2
   [junit4]   2> 1055887 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
   [junit4]   2> 1055888 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
   [junit4]   2> 1055889 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
   [junit4]   2> 1055890 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
   [junit4]   2> 1055892 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
   [junit4]   2> 1055892 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
   [junit4]   2> 1055893 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43465.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a393aac
   [junit4]   2> 1055893 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001/tempDir-001/node2/.system_shard1_replica_n1/data/]
   [junit4]   2> 1055936 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1055936 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1055937 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs; 
   [junit4]   2> 1055937 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1055939 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
   [junit4]   2> 1055939 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 1055939 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
   [junit4]   2> 1055939 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 1055940 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 1055940 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1693900468728627200
   [junit4]   2> 1055942 INFO  (searcherExecutor-10232-thread-1-processing-n:127.0.0.1:43465_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1055945 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
   [junit4]   2> 1055945 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
   [junit4]   2> 1055948 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1055949 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1055949 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43465/solr/.system_shard1_replica_n1/
   [junit4]   2> 1055949 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1055949 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:43465/solr/.system_shard1_replica_n1/ has no replicas
   [junit4]   2> 1055949 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/73137336574935047-core_node2-n_0000000000
   [junit4]   2> 1055952 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43465/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 1055952 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "operation":"leader"} current state version: 0
   [junit4]   2> 1056053 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
   [junit4]   2> 1056053 INFO  (zkCallback-10202-thread-1) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056053 INFO  (zkCallback-10202-thread-3) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056053 INFO  (zkCallback-10202-thread-2) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056054 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1056056 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node2",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1056056 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node2",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1056056 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "leader":"true"}
   [junit4]   2> 1056056 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/2 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"1",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node2":{
   [junit4]   2>           "core":".system_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:43465_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43465/solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}}}
   [junit4]   2> 1056057 INFO  (qtp161308119-16837) [n:127.0.0.1:43465_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=197
   [junit4]   2> 1056058 INFO  (qtp161308119-16834) [n:127.0.0.1:43465_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> 1056156 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
   [junit4]   2> 1056157 INFO  (zkCallback-10202-thread-1) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056157 INFO  (zkCallback-10202-thread-3) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056157 INFO  (zkCallback-10202-thread-2) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056157 INFO  (zkCallback-10202-thread-4) [     ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1056157 INFO  (qtp161308119-16834) [n:127.0.0.1:43465_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:43465_solr&wt=javabin&version=2} status=0 QTime=612
   [junit4]   2> 1056158 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 1056167 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 1056167 INFO  (OverseerCollectionConfigSetProcessor-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_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> 1056167 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
   [junit4]   2> 1056168 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
   [junit4]   2> 1056169 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
   [junit4]   2> 1056171 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1056172 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
   [junit4]   2> 1056174 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
   [junit4]   2> 1056175 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
   [junit4]   2> 1056176 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
   [junit4]   2> 1056178 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
   [junit4]   2> 1056179 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
   [junit4]   2> 1056180 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_et.txt
   [junit4]   2> 1056182 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
   [junit4]   2> 1056183 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
   [junit4]   2> 1056184 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
   [junit4]   2> 1056186 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
   [junit4]   2> 1056187 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
   [junit4]   2> 1056188 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
   [junit4]   2> 1056189 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
   [junit4]   2> 1056191 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
   [junit4]   2> 1056192 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
   [junit4]   2> 1056193 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
   [junit4]   2> 1056194 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
   [junit4]   2> 1056196 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
   [junit4]   2> 1056197 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
   [junit4]   2> 1056198 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
   [junit4]   2> 1056199 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
   [junit4]   2> 1056201 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
   [junit4]   2> 1056202 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
   [junit4]   2> 1056203 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
   [junit4]   2> 1056205 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
   [junit4]   2> 1056206 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
   [junit4]   2> 1056207 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
   [junit4]   2> 1056208 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
   [junit4]   2> 1056209 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
   [junit4]   2> 1056210 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
   [junit4]   2> 1056211 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
   [junit4]   2> 1056213 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
   [junit4]   2> 1056214 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
   [junit4]   2> 1056215 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
   [junit4]   2> 1056216 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
   [junit4]   2> 1056217 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
   [junit4]   2> 1056218 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
   [junit4]   2> 1056220 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
   [junit4]   2> 1056221 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
   [junit4]   2> 1056222 INFO  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
   [junit4]   2> 1056225 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "name":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "maxShardsPerNode":"3",
   [junit4]   2>   "createNodeSet":"127.0.0.1:43581_solr",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1056225 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 1056326 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
   [junit4]   2> 1056426 WARN  (OverseerThreadFactory-10210-thread-2-processing-n:127.0.0.1:43465_solr) [n:127.0.0.1:43465_solr     ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 1056430 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1056430 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1056431 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1056431 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node3":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"}}}
   [junit4]   2> 1056431 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1056431 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1056432 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node3":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"}}}
   [junit4]   2> 1056433 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node3":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"}}}
   [junit4]   2> 1056433 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1056433 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1056434 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node3":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"}}}
   [junit4]   2> 1056434 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{
   [junit4]   2>     "core_node3":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"},
   [junit4]   2>     "core_node6":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>       "type":"NRT",
   [junit4]   2>       "base_url":"http://127.0.0.1:43581/solr"}}}
   [junit4]   2> 1056452 DEBUG (ScheduledTrigger-10208-thread-3) [     ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
   [junit4]   2> 1056535 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
   [junit4]   2> 1056637 INFO  (qtp623920906-16828) [n:127.0.0.1:43581_solr    x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n4&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1056637 INFO  (qtp623920906-16830) [n:127.0.0.1:43581_solr    x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1056637 INFO  (qtp623920906-16833) [n:127.0.0.1:43581_solr    x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node5",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "core_node_name":"core_node5",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"3",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"},
   [junit4]   2>         "core_node6":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr"}}}}}
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1056641 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"3",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"},
   [junit4]   2>         "core_node6":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr"}}}}}
   [junit4]   2> 1056646 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "core_node_name":"core_node6",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1056646 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "core_node_name":"core_node6",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1056646 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>   "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1056647 DEBUG (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"3",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{"shard1":{
   [junit4]   2>       "range":"80000000-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{
   [junit4]   2>         "core_node3":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"},
   [junit4]   2>         "core_node6":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>           "node_name":"127.0.0.1:43581_solr",
   [junit4]   2>           "base_url":"http://127.0.0.1:43581/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"}}}}}
   [junit4]   2> 1056651 INFO  (qtp623920906-16830) [n:127.0.0.1:43581_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.c.So

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

stry=solr.jvm tag=null
   [junit4]   2> 1121207 INFO  (jetty-closer-10282-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36220c9: rootName = solr_43465, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5165558d
   [junit4]   2> 1121209 INFO  (jetty-closer-10282-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 1121209 INFO  (jetty-closer-10282-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@54b1ddea: rootName = solr_43465, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@7dbd6c7b
   [junit4]   2> 1121209 INFO  (jetty-closer-10282-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
   [junit4]   2> 1121225 INFO  (closeThreadPool-10299-thread-2) [     ] o.a.s.c.Overseer Overseer (id=73137336574935047-127.0.0.1:43465_solr-n_0000000000) closing
   [junit4]   2> 1121231 INFO  (OverseerStateUpdate-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [n:127.0.0.1:43465_solr     ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43465_solr
   [junit4]   2> 1121243 INFO  (OverseerAutoScalingTriggerThread-73137336574935047-127.0.0.1:43465_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
   [junit4]   2> 1121243 DEBUG (closeThreadPool-10299-thread-2) [     ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 1121243 INFO  (closeThreadPool-10299-thread-1) [     ] o.a.s.c.Overseer Overseer (id=73137336574935047-127.0.0.1:43465_solr-n_0000000000) closing
   [junit4]   2> 1121371 INFO  (jetty-closer-10282-thread-1) [     ] o.a.s.c.Overseer Overseer (id=73137336574935047-127.0.0.1:43465_solr-n_0000000000) closing
   [junit4]   2> 1121375 INFO  (jetty-closer-10282-thread-1) [     ] o.e.j.s.AbstractConnector Stopped ServerConnector@75aa294{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
   [junit4]   2> 1121375 INFO  (jetty-closer-10282-thread-1) [     ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1368ec47{/solr,null,STOPPED}
   [junit4]   2> 1121375 INFO  (jetty-closer-10282-thread-1) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1121377 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1121582 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> 	5	/solr/clusterprops.json
   [junit4]   2> 	5	/solr/aliases.json
   [junit4]   2> 	4	/solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
   [junit4]   2> 	3	/solr/packages.json
   [junit4]   2> 	3	/solr/security.json
   [junit4]   2> 	3	/solr/collections/.system/terms/shard1
   [junit4]   2> 	3	/solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	17	/solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
   [junit4]   2> 	17	/solr/collections/.system/state.json
   [junit4]   2> 	12	/solr/autoscaling.json
   [junit4]   2> 	5	/solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	34	/solr/overseer/queue
   [junit4]   2> 	17	/solr/live_nodes
   [junit4]   2> 	11	/solr/collections
   [junit4]   2> 	9	/solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
   [junit4]   2> 	8	/solr/overseer/collection-queue-work
   [junit4]   2> 	6	/solr/collections/.system/state.json
   [junit4]   2> 
   [junit4]   2> 1121587 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34203
   [junit4]   2> 1121587 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34203
   [junit4]   2> 1121587 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[65EDBDBBA7D3DFAE]-worker) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34203
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_65EDBDBBA7D3DFAE-001
   [junit4]   2> Mar 11, 2021 2:23:57 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=Asserting(Lucene87): {action_s=PostingsFormat(name=LuceneFixedGap), event.property.preferredOperation_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), event.source_s=PostingsFormat(name=Asserting), event.property.replicaType_ss=PostingsFormat(name=LuceneFixedGap), type=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), before.actions_ss=BlockTreeOrds(blocksize=128), event.property._enqueue_time__ss=PostingsFormat(name=LuceneFixedGap), error.details_t=BlockTreeOrds(blocksize=128), stage_s=PostingsFormat(name=LuceneFixedGap), event.id_s=PostingsFormat(name=Asserting), event.property.eventTimes_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), source_s=PostingsFormat(name=Asserting), event.type_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=PostingsFormat(name=Asserting), event.property.nodeNames_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), error.message_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{_version_=DocValuesFormat(name=Asserting), event.time_l=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1856, maxMBSortInHeap=7.862976989739028, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=cs-CZ, timezone=America/Montserrat
   [junit4]   2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=250250376,total=512753664
   [junit4]   2> NOTE: All tests run in this JVM: [DistributedExpandComponentTest, TestXIncludeConfig, DeleteShardTest, HdfsTlogReplayBufferedWhileIndexingTest, TestSchemaVersionResource, TestMacroExpander, TestQueryTypes, TestDynamicFieldResource, TestChildDocTransformer, TestRequestForwarding, ZkShardTermsTest, SecurityConfHandlerTest, ComputePlanActionTest, DirectSolrConnectionTest, TestGraphTermsQParserPlugin, ZkCLITest, TestDocumentBuilder, TestRangeQuery, TestOverriddenPrefixQueryForCustomFieldType, TestBM25SimilarityFactory, LeaderVoteWaitTimeoutTest, TestReplicationHandler, BlockCacheTest, BlockJoinFacetRandomTest, SolrInfoBeanTest, MultiDestinationAuditLoggerTest, DateFieldTest, TestSimPolicyCloud, BasicDistributedZk2Test, TestCryptoKeys, HdfsChaosMonkeyNothingIsSafeTest, TestCloudInspectUtil, TestConfigReload, OverseerModifyCollectionTest, AnalyticsMergeStrategyTest, TaggerTest, TestRawResponseWriter, TestExternalRoleRuleBasedAuthorizationPlugin, TestDocSet, ResponseLogComponentTest, CdcrOpsAndBoundariesTest, TestSolrDeletionPolicy1, TestNumericTokenStream, TestStressVersions, MetricTriggerTest, TestFreeTextSuggestions, TestSchemaField, TestRandomDVFaceting, TestMinimalConfig, PeerSyncWithLeaderTest, TestDownShardTolerantSearch, ClusterStateUpdateTest, TestQueryingOnDownCollection, AlternateDirectoryTest, TestSortByMinMaxFunction, TestSimDistribStateManager, SolrSlf4jReporterTest, TestDynamicLoadingUrl, OverseerStatusTest, NumberUtilsTest, TestEmbeddedSolrServerConstructors, FieldAnalysisRequestHandlerTest, TestManagedSynonymFilterFactory, CloneFieldUpdateProcessorFactoryTest, TestCollationField, TestRequestStatusCollectionAPI, ActionThrottleTest, TestClusterStateMutator, TestMinHashQParser, HighlighterConfigTest, ScheduledTriggerIntegrationTest, TestBinaryField, TermVectorComponentTest, TriggerIntegrationTest, TestTlogReplayVsRecovery, FuzzySearchTest, VersionInfoTest, JavaBinAtomicUpdateMultivalueTest, ConcurrentCreateCollectionTest, DirectoryFactoryTest, SolrCloudExampleTest, TestCoreDiscovery, RequestLoggingTest, TestSolrConfigHandlerConcurrent, ImplicitSnitchTest, DistributedFacetPivotSmallAdvancedTest, RestartWhileUpdatingTest, JavabinLoaderTest, TestLeaderElectionWithEmptyReplica, UninvertDocValuesMergePolicyTest, TestReloadAndDeleteDocs, TestRecoveryHdfs, SSLMigrationTest, IndexSchemaTest, ConcurrentCreateRoutedAliasTest, TestDFRSimilarityFactory, TestCloudNestedDocsSort, DateMathParserTest, TestFastOutputStream, TestRestoreCore, TestSnapshotCloudManager, SecurityHeadersTest, TestInitParams, TestHttpShardHandlerFactory, TestBinaryResponseWriter, StatsComponentTest, SolrIndexConfigTest, AutoscalingHistoryHandlerTest]
   [junit4] Completed [400/923 (1!)] on J2 in 67.71s, 1 test, 1 failure <<< FAILURES!

[...truncated 52526 lines...]