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

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

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

2 tests failed.
FAILED:  org.apache.solr.cloud.TestWithCollection.testNodeAdded

Error Message:
Action was not fired till 30 seconds

Stack Trace:
java.lang.AssertionError: Action was not fired till 30 seconds
	at __randomizedtesting.SeedInfo.seed([F02357FD39827C96:95E0018A9B21D495]:0)
	at org.junit.Assert.fail(Assert.java:89)
	at org.junit.Assert.assertTrue(Assert.java:42)
	at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:471)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory

Error Message:
action did not execute

Stack Trace:
java.lang.AssertionError: action did not execute
	at __randomizedtesting.SeedInfo.seed([F02357FD39827C96:9DDFF30083CA8391]: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 13699 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
   [junit4]   2> 272672 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-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> 272672 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 272673 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-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_F02357FD39827C96-001/data-dir-24-001
   [junit4]   2> 272673 WARN  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
   [junit4]   2> 272673 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 272675 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 272676 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.SolrCloudTestCase Using per-replica state
   [junit4]   2> 272676 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-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_F02357FD39827C96-001/tempDir-001
   [junit4]   2> 272676 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 272676 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 272677 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 272677 WARN  (ZkTestServer Run Thread) [     ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
   [junit4]   2> 272777 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.ZkTestServer start zk server on port: 32817
   [junit4]   2> 272777 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:32817
   [junit4]   2> 272777 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:32817
   [junit4]   2> 272777 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 32817
   [junit4]   2> 272778 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 272781 INFO  (zkConnectionManagerCallback-2171-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 272781 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 272783 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 272784 INFO  (zkConnectionManagerCallback-2173-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 272784 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 272785 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 272786 INFO  (zkConnectionManagerCallback-2175-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 272786 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 272889 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 272889 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 272889 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 272889 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 272889 INFO  (jetty-launcher-2176-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> 272889 INFO  (jetty-launcher-2176-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> 272891 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11a2e17e{/solr,null,AVAILABLE}
   [junit4]   2> 272891 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f7c1fe5{/solr,null,AVAILABLE}
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.AbstractConnector Started ServerConnector@1a578e40{SSL, (ssl, http/1.1)}{127.0.0.1:38115}
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-1) [     ] o.e.j.s.Server Started @272926ms
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:32817/solr, hostPort=38115}
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.AbstractConnector Started ServerConnector@3ed2176c{SSL, (ssl, http/1.1)}{127.0.0.1:46809}
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-2) [     ] o.e.j.s.Server Started @272926ms
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:32817/solr, hostPort=46809}
   [junit4]   2> 272892 ERROR (jetty-launcher-2176-thread-1) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 272892 ERROR (jetty-launcher-2176-thread-2) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 272892 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.8.0
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.8.0
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-02-04T07:22:42.705Z
   [junit4]   2> 272893 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2021-02-04T07:22:42.705Z
   [junit4]   2> 272896 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 272896 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 272897 INFO  (zkConnectionManagerCallback-2179-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 272897 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 272897 INFO  (zkConnectionManagerCallback-2180-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 272897 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 272898 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 272898 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 273478 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 273478 WARN  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 273482 WARN  (jetty-launcher-2176-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6a300f19[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 273482 WARN  (jetty-launcher-2176-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6a300f19[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 273484 WARN  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 273485 WARN  (jetty-launcher-2176-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60301f12[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 273485 WARN  (jetty-launcher-2176-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60301f12[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 273487 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32817/solr
   [junit4]   2> 273490 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 273491 INFO  (zkConnectionManagerCallback-2194-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 273491 INFO  (jetty-launcher-2176-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 273594 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 273595 INFO  (zkConnectionManagerCallback-2196-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 273595 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 273658 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38115_solr
   [junit4]   2> 273659 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer Overseer (id=72940334856732678-127.0.0.1:38115_solr-n_0000000000) starting
   [junit4]   2> 273663 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38115_solr
   [junit4]   2> 273663 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38115_solr
   [junit4]   2> 273664 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
   [junit4]   2> 273666 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 273674 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
   [junit4]   2> 273674 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
   [junit4]   2> 273674 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
   [junit4]   2> 273675 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 273675 WARN  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_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> 273683 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:38115_solr]
   [junit4]   2> 273687 DEBUG (ScheduledTrigger-2201-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> 273690 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
   [junit4]   2> 273690 DEBUG (OverseerAutoScalingTriggerThread-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 273700 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 273732 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38115.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 273739 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38115.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 273739 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38115.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 273740 INFO  (jetty-launcher-2176-thread-1) [n:127.0.0.1:38115_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_F02357FD39827C96-001/tempDir-001/node1
   [junit4]   2> 274033 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 274033 WARN  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 274034 WARN  (jetty-launcher-2176-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@122035c2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 274034 WARN  (jetty-launcher-2176-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@122035c2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 274037 WARN  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 274038 WARN  (jetty-launcher-2176-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@66145a39[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 274038 WARN  (jetty-launcher-2176-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@66145a39[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 274039 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32817/solr
   [junit4]   2> 274039 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 274040 INFO  (zkConnectionManagerCallback-2216-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 274040 INFO  (jetty-launcher-2176-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 274142 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 274144 INFO  (zkConnectionManagerCallback-2218-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 274144 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 274151 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 274156 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:46809_solr as DOWN
   [junit4]   2> 274157 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 274157 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46809_solr
   [junit4]   2> 274159 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr"} current state version: 0
   [junit4]   2> 274159 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:46809_solr
   [junit4]   2> 274160 INFO  (zkCallback-2195-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 274160 INFO  (zkCallback-2217-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 274161 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 274162 WARN  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_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> 274173 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 274192 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46809.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 274201 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46809.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 274201 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46809.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 274202 INFO  (jetty-launcher-2176-thread-2) [n:127.0.0.1:46809_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_F02357FD39827C96-001/tempDir-001/node2
   [junit4]   2> 274219 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 274219 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 274221 INFO  (zkConnectionManagerCallback-2231-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 274221 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 274222 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 274223 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32817/solr ready
   [junit4]   2> 274261 INFO  (OverseerThreadFactory-2203-thread-1-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
   [junit4]   2> 274267 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "name":".system",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "numShards":1,
   [junit4]   2>   "createNodeSet":"127.0.0.1:38115_solr",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 274267 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
   [junit4]   2> 274267 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
   [junit4]   2> 274371 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:38115_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 274371 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:38115_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 274372 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 274372 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:38115_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 274473 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
   [junit4]   2> 274604 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274604 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr    x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 274606 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274610 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_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> 274610 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_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> 274611 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "base_url":"https://127.0.0.1:38115/solr"}
   [junit4]   2> 274611 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"}}}}}
   [junit4]   2> 274616 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
   [junit4]   2> 274622 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274623 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274624 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274626 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274627 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274628 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274628 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38115.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@694d5739
   [junit4]   2> 274628 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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_F02357FD39827C96-001/tempDir-001/node1/.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_F02357FD39827C96-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
   [junit4]   2> 274686 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274686 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274688 DEBUG (ScheduledTrigger-2201-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> 274688 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274688 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 274690 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274690 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274690 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274691 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274691 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274692 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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 1690748441858146304
   [junit4]   2> 274694 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274695 INFO  (searcherExecutor-2233-thread-1-processing-n:127.0.0.1:38115_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:38115_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> 274698 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274698 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274701 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274702 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274702 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38115/solr/.system_shard1_replica_n1/
   [junit4]   2> 274702 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274702 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:38115/solr/.system_shard1_replica_n1/ has no replicas
   [junit4]   2> 274702 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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/72940334856732678-core_node2-n_0000000000
   [junit4]   2> 274705 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38115/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 274705 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274705 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "node_name":"127.0.0.1:38115_solr",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"active"} current state version: 0
   [junit4]   2> 274806 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
   [junit4]   2> 274807 INFO  (zkCallback-2195-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> 274807 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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> 274809 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274809 INFO  (zkCallback-2195-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> 274809 INFO  (zkCallback-2195-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> 274811 INFO  (qtp279962928-3855) [n:127.0.0.1:38115_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=207
   [junit4]   2> 274811 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_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> 274811 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_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> 274811 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:38115_solr",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "leader":"true",
   [junit4]   2>   "base_url":"https://127.0.0.1:38115/solr"}
   [junit4]   2> 274812 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>           "leader":"true",
   [junit4]   2>           "node_name":"127.0.0.1:38115_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"}}}}}
   [junit4]   2> 274814 INFO  (qtp875199516-3846) [n:127.0.0.1:46809_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> 274814 INFO  (qtp875199516-3846) [n:127.0.0.1:46809_solr     ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274917 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
   [junit4]   2> 274919 INFO  (zkCallback-2195-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> 274919 INFO  (zkCallback-2195-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> 274919 INFO  (zkCallback-2195-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> 274919 INFO  (zkCallback-2217-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> 274935 INFO  (qtp875199516-3846) [n:127.0.0.1:46809_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:38115_solr&wt=javabin&version=2} status=0 QTime=681
   [junit4]   2> 274935 INFO  (zkCallback-2195-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> 274936 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 274936 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[F02357FD39827C96]-worker) [     ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 274962 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 274963 INFO  (OverseerCollectionConfigSetProcessor-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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> 274963 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
   [junit4]   2> 274964 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
   [junit4]   2> 274964 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
   [junit4]   2> 274966 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
   [junit4]   2> 274967 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274968 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274969 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274970 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274971 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274972 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274973 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274974 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274975 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274976 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274977 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274978 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274979 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274980 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274981 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274982 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274983 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274984 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274985 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274986 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274987 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274988 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274989 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274990 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274991 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274992 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274994 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274995 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274995 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274996 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274997 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274998 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 274999 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275000 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275001 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275002 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275003 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275004 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275005 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275006 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
   [junit4]   2> 275007 INFO  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
   [junit4]   2> 275011 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_solr",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 275011 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 275111 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
   [junit4]   2> 275215 WARN  (OverseerThreadFactory-2203-thread-2-processing-n:127.0.0.1:38115_solr) [n:127.0.0.1:38115_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> 275220 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 275220 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 275221 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 275221 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 275222 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 275222 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 275223 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 275224 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 275224 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 275224 INFO  (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"AutoscalingHistoryHandlerTest_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 275225 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 275225 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node6":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>       "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 275325 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
   [junit4]   2> 275433 INFO  (qtp875199516-3854) [n:127.0.0.1:46809_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> 275434 INFO  (qtp875199516-3854) [n:127.0.0.1:46809_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 275441 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_solr     ] o.a.s.c.Overseer processMessage: queueSize: 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:46809_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> 275441 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_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> 275441 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "base_url":"https://127.0.0.1:46809/solr"}
   [junit4]   2> 275441 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_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:46809_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node6":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>           "node_name":"127.0.0.1:46809_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 275446 INFO  (qtp875199516-3850) [n:127.0.0.1:46809_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> 275451 INFO  (qtp875199516-3850) [n:127.0.0.1:46809_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 275458 INFO  (qtp875199516-3854) [n:127.0.0.1:46809_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
   [junit4]   2> 275460 INFO  (qtp875199516-3846) [n:127.0.0.1:46809_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> 275463 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_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> 275463 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_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> 275463 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "base_url":"https://127.0.0.1:46809/solr"}
   [junit4]   2> 275463 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000) [n:127.0.0.1:38115_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:46809_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:46809_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:46809_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 275465 INFO  (qtp875199516-3846) [n:127.0.0.1:46809_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
   [junit4]   2> 275483 INFO  (qtp875199516-3850) [n:127.0.0.1:46809_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
   [junit4]   2> 275491 DEBUG (OverseerStateUpdate-72940334856732678-127.0.0.1:38115_solr-n_0000000000

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

plicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4 x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false]
   [junit4]   2> 1652757 INFO  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.c.SolrCore [testAddReplicaWithPolicy_xyz_shard1_replica_n3]  CLOSING SolrCore org.apache.solr.core.SolrCore@7fee5cfe
   [junit4]   2> 1652757 INFO  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3 tag=SolrCore@7fee5cfe
   [junit4]   2> 1652757 INFO  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e129610: rootName = solr_45507, domain = solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3, service url = null, agent id = null] for registry solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3/com.codahale.metrics.MetricRegistry@257fb0d4
   [junit4]   2> 1652774 INFO  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAddReplicaWithPolicy_xyz.shard1.leader tag=SolrCore@7fee5cfe
   [junit4]   2> 1652775 WARN  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[testAddReplicaWithPolicy_xyz_shard1_replica_n3] coreNodeName=[core_node4]
   [junit4]   2> 1652775 INFO  (recoveryExecutor-15330-thread-1-processing-n:127.0.0.1:45507_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:45507_solr     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 1652778 INFO  (jetty-closer-15374-thread-2) [     ] o.e.j.s.AbstractConnector Stopped ServerConnector@6301f172{SSL, (ssl, http/1.1)}{127.0.0.1:0}
   [junit4]   2> 1652778 INFO  (jetty-closer-15374-thread-2) [     ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6c214172{/solr,null,STOPPED}
   [junit4]   2> 1652778 INFO  (jetty-closer-15374-thread-2) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1652779 INFO  (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[F02357FD39827C96]) [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1652982 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/collections/testAddReplicaWithPolicy_xyz/terms/shard1
   [junit4]   2> 	5	/solr/collections/testAddReplicaWithPolicy_abc/terms/shard1
   [junit4]   2> 	3	/solr/aliases.json
   [junit4]   2> 	2	/solr/packages.json
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 	2	/solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
   [junit4]   2> 	2	/solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
   [junit4]   2> 	2	/solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
   [junit4]   2> 	2	/solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
   [junit4]   2> 	2	/solr/collections/testAddReplicaWithPolicy_abc/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	34	/solr/collections/testAddReplicaWithPolicy_abc/state.json
   [junit4]   2> 	25	/solr/collections/testAddReplicaWithPolicy_xyz/state.json
   [junit4]   2> 	3	/solr/clusterstate.json
   [junit4]   2> 	3	/solr/autoscaling.json
   [junit4]   2> 	3	/solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	39	/solr/overseer/queue
   [junit4]   2> 	11	/solr/overseer/collection-queue-work
   [junit4]   2> 	9	/solr/collections
   [junit4]   2> 	8	/solr/collections/testAddReplicaWithPolicy_xyz/state.json
   [junit4]   2> 	8	/solr/collections/testAddReplicaWithPolicy_abc/state.json
   [junit4]   2> 	6	/solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 1652986 INFO  (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[F02357FD39827C96]) [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41849
   [junit4]   2> 1652986 INFO  (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[F02357FD39827C96]) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41849
   [junit4]   2> 1652986 INFO  (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[F02357FD39827C96]) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41849
   [junit4]   2> 1652987 INFO  (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[F02357FD39827C96]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testAddReplicaWithPolicy
   [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.cloud.TestWithCollection_F02357FD39827C96-001
   [junit4]   2> Feb 04, 2021 7:45:42 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=sr-RS, timezone=Pacific/Rarotonga
   [junit4]   2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=173798520,total=521142272
   [junit4]   2> NOTE: All tests run in this JVM: [ActionThrottleTest, SystemLogListenerTest, TestSolrCloudWithSecureImpersonation, TestCollationField, SecurityHeadersTest, MoveReplicaTest, TestRequestStatusCollectionAPI, JavaBinAtomicUpdateMultivalueTest, TestBinaryField, TestCharFilters, DeleteInactiveReplicaTest, DocExpirationUpdateProcessorFactoryTest, ScheduledTriggerIntegrationTest, IndexSchemaTest, TestStressVersions, TestConfigSetImmutable, UninvertDocValuesMergePolicyTest, TestLegacyTerms, MBeansHandlerTest, TestFieldTypeResource, SolrCloudExampleTest, TestDownShardTolerantSearch, TestSnapshotCloudManager, TestExactSharedStatsCache, AutoscalingHistoryHandlerTest, DateMathParserTest, TestSimGenericDistributedQueue, CollectionReloadTest, TestRestManager, DeleteShardTest, XMLAtomicUpdateMultivalueTest, CdcrWithNodesRestartsTest, TestDocSet, DistributedExpandComponentTest, FileBasedSpellCheckerTest, PeerSyncTest, DistributedSuggestComponentTest, LeaderElectionTest, FuzzySearchTest, StatsComponentTest, TestCryptoKeys, AnalyticsMergeStrategyTest, ZookeeperReadAPITest, TestRequestForwarding, TestCloudRecovery, CategoryRoutedAliasUpdateProcessorTest, QueryEqualityTest, NodeLostTriggerTest, SSLMigrationTest, TestMinimalConfig, TestSolrConfigHandlerConcurrent, TestCloudNestedDocsSort, TestSchemaField, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestDynamicFieldResource, TestEmbeddedSolrServerConstructors, ConcurrentCreateRoutedAliasTest, TestMinHashQParser, TaggerTest, SecurityConfHandlerTest, TestBinaryResponseWriter, TestJsonFacetsStatsParsing, CloneFieldUpdateProcessorFactoryTest, TestOnReconnectListenerSupport, RegexBoostProcessorTest, TestComplexPhraseLeadingWildcard, IndexSizeEstimatorTest, TestUtilizeNode, TestUpdate, TestFieldSortValues, TestHttpServletCarrier, TestSortByMinMaxFunction, TermVectorComponentTest, DirectoryFactoryTest, TestXIncludeConfig, TestQueryingOnDownCollection, TestInPlaceUpdatesRequiredField, BJQFilterAccessibleTest, HttpPartitionTest, TestChildDocTransformer, TestCollectionsAPIViaSolrCloudCluster, TestRandomDVFaceting, RestartWhileUpdatingTest, PrimitiveFieldTypeTest, CachingDirectoryFactoryTest, CleanupOldIndexTest, DateFieldTest, URLClassifyProcessorTest, TestClusterStateMutator, JavabinLoaderTest, ComputePlanActionTest, TestInitParams, TestDistribPackageStore, TestConfigReload, TestHttpShardHandlerFactory, ReturnFieldsTest, ShardTermsTest, JWTAuthPluginIntegrationTest, RangeFacetCloudTest, DistribJoinFromCollectionTest, TestManagedSchemaAPI, TestGraphMLResponseWriter, HighlighterConfigTest, SplitHandlerTest, SolrIndexConfigTest, OverseerStatusTest, QueryResultKeyTest, TestAnalyzeInfixSuggestions, ExplicitHLLTest, TestStressLucene, TestLFUCache, DistributedFacetPivotSmallAdvancedTest, OpenExchangeRatesOrgProviderTest, AlternateDirectoryTest, TestSizeLimitedDistributedMap, CloudExitableDirectoryReaderTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, DeleteLastCustomShardedReplicaTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, HttpPartitionWithTlogReplicasTest, LeaderVoteWaitTimeoutTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, RollingRestartTest, SolrXmlInZkTest, SplitShardTest, SystemCollectionCompatTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudSearcherWarming, TestRandomFlRTGCloud, TestWithCollection]
   [junit4] Completed [566/923 (2!)] on J2 in 88.02s, 9 tests, 1 failure <<< FAILURES!

[...truncated 51952 lines...]