You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/11/26 09:23:51 UTC

[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 912 - Unstable!

Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/912/

2 tests failed.
FAILED:  org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica

Error Message:
Error from server at http://127.0.0.1:35667/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35667/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
	at __randomizedtesting.SeedInfo.seed([3904DBBA4F4DFDDC:31333D7294E38952]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
	at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1173)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
	at org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica(TestPullReplicaErrorHandling.java:149)
	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([3904DBBA4F4DFDDC:54F87F47F50502DB]: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 14683 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
   [junit4]   2> 1189382 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 1189382 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1189383 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/data-dir-116-001
   [junit4]   2> 1189384 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1189384 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1189385 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/tempDir-001
   [junit4]   2> 1189400 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1189415 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1189415 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1189416 WARN  (ZkTestServer Run Thread) [     ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
   [junit4]   2> 1189515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer start zk server on port: 34569
   [junit4]   2> 1189515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34569
   [junit4]   2> 1189515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34569
   [junit4]   2> 1189515 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34569
   [junit4]   2> 1189530 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1189560 INFO  (zkConnectionManagerCallback-12076-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1189560 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1189625 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1189649 INFO  (zkConnectionManagerCallback-12078-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1189649 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1189869 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1189889 INFO  (zkConnectionManagerCallback-12080-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1189889 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1189890 WARN  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker-SendThread(127.0.0.1:34569)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101baa3baf20002.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20002, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20002, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 1189992 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1189992 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1189992 INFO  (jetty-launcher-12081-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_252-b09
   [junit4]   2> 1189993 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1189993 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1189993 INFO  (jetty-launcher-12081-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_252-b09
   [junit4]   2> 1189997 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1189997 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1189997 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1189998 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50f2bf66{/solr,null,AVAILABLE}
   [junit4]   2> 1190014 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1190014 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1190014 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.AbstractConnector Started ServerConnector@217d7403{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:43239}
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.e.j.s.Server Started @1190047ms
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43239}
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d7db4b7{/solr,null,AVAILABLE}
   [junit4]   2> 1190015 ERROR (jetty-launcher-12081-thread-1) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.8.0
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-11-26T08:43:30.918Z
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.AbstractConnector Started ServerConnector@75191b7c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42919}
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.e.j.s.Server Started @1190048ms
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42919}
   [junit4]   2> 1190015 ERROR (jetty-launcher-12081-thread-2) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.8.0
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
   [junit4]   2> 1190015 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-11-26T08:43:30.918Z
   [junit4]   2> 1190016 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1190030 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1190241 INFO  (zkConnectionManagerCallback-12085-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1190241 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1190242 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1190244 INFO  (zkConnectionManagerCallback-12083-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1190244 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1190247 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1190248 WARN  (jetty-launcher-12081-thread-2-SendThread(127.0.0.1:34569)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101baa3baf20004.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20004, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20004, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 1192414 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1192438 WARN  (jetty-launcher-12081-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7bc41492[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1192438 WARN  (jetty-launcher-12081-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7bc41492[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1192477 WARN  (jetty-launcher-12081-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@516565f4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1192477 WARN  (jetty-launcher-12081-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@516565f4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1192478 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34569/solr
   [junit4]   2> 1192502 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1192507 INFO  (zkConnectionManagerCallback-12099-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1192508 INFO  (jetty-launcher-12081-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1192983 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1192993 INFO  (zkConnectionManagerCallback-12101-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1192993 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1193062 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42919_solr
   [junit4]   2> 1193062 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.Overseer Overseer (id=72544281393496070-127.0.0.1:42919_solr-n_0000000000) starting
   [junit4]   2> 1193067 INFO  (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42919_solr
   [junit4]   2> 1193073 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42919_solr
   [junit4]   2> 1193074 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
   [junit4]   2> 1193075 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
   [junit4]   2> 1193075 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1193075 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
   [junit4]   2> 1193075 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
   [junit4]   2> 1193076 WARN  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_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> 1193084 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1193089 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:42919_solr]
   [junit4]   2> 1193094 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
   [junit4]   2> 1193095 DEBUG (OverseerAutoScalingTriggerThread-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 1193095 DEBUG (ScheduledTrigger-12106-thread-3) [     ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
   [junit4]   2> 1193099 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1193127 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42919.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193134 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42919.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193134 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42919.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193135 INFO  (jetty-launcher-12081-thread-2) [n:127.0.0.1:42919_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/tempDir-001/node2
   [junit4]   2> 1193378 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1193379 WARN  (jetty-launcher-12081-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b39433b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1193380 WARN  (jetty-launcher-12081-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b39433b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1193382 WARN  (jetty-launcher-12081-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@499b3b76[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1193382 WARN  (jetty-launcher-12081-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@499b3b76[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1193383 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34569/solr
   [junit4]   2> 1193610 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1193611 INFO  (zkConnectionManagerCallback-12121-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1193611 INFO  (jetty-launcher-12081-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1193612 WARN  (jetty-launcher-12081-thread-1-SendThread(127.0.0.1:34569)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101baa3baf20007.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20007, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101baa3baf20007, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 1193713 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1193714 INFO  (zkConnectionManagerCallback-12123-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1193714 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1193727 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1193730 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:43239_solr as DOWN
   [junit4]   2> 1193731 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1193731 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43239_solr
   [junit4]   2> 1193732 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr"} current state version: 0
   [junit4]   2> 1193732 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:43239_solr
   [junit4]   2> 1193732 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1193734 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1193734 INFO  (zkCallback-12122-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1193734 WARN  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_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> 1193761 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1193780 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43239.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193788 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43239.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193789 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43239.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1193790 INFO  (jetty-launcher-12081-thread-1) [n:127.0.0.1:43239_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/tempDir-001/node1
   [junit4]   2> 1193825 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1193826 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1193829 INFO  (zkConnectionManagerCallback-12136-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1193829 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1193830 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1193831 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34569/solr ready
   [junit4]   2> 1193845 INFO  (OverseerThreadFactory-12108-thread-1-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
   [junit4]   2> 1193847 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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:42919_solr",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1193847 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
   [junit4]   2> 1193848 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
   [junit4]   2> 1193951 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1193951 INFO  (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1193952 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1193952 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194052 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
   [junit4]   2> 1194096 DEBUG (ScheduledTrigger-12106-thread-2) [     ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
   [junit4]   2> 1194158 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194158 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr    x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1194164 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_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> 1194164 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_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> 1194164 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1194164 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>           "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>           "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false"}}}}}
   [junit4]   2> 1194182 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194188 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194197 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194201 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194203 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194205 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194206 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194206 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42919.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ec5a32f
   [junit4]   2> 1194206 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_3904DBBA4F4DFDDC-001/tempDir-001/node2/.system_shard1_replica_n1/data/]
   [junit4]   2> 1194252 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194252 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194253 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194253 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1194257 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194257 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194257 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194258 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194258 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194258 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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 1684411740492660736
   [junit4]   2> 1194262 INFO  (searcherExecutor-12138-thread-1-processing-n:127.0.0.1:42919_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:42919_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> 1194263 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194263 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194265 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
   [junit4]   2> 1194269 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194269 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194269 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42919/solr/.system_shard1_replica_n1/
   [junit4]   2> 1194269 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194273 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:42919/solr/.system_shard1_replica_n1/ has no replicas
   [junit4]   2> 1194273 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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/72544281393496070-core_node2-n_0000000000
   [junit4]   2> 1194274 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42919/solr/.system_shard1_replica_n1/ shard1
   [junit4]   2> 1194279 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"active"} current state version: 0
   [junit4]   2> 1194380 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
   [junit4]   2> 1194381 INFO  (zkCallback-12100-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> 1194383 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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> 1194386 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_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> 1194386 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_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> 1194386 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>   "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false",
   [junit4]   2>   "leader":"true"}
   [junit4]   2> 1194386 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3)={
   [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>           "base_url":"http://127.0.0.1:42919/solr",
   [junit4]   2>           "node_name":"127.0.0.1:42919_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "force_set_state":"false",
   [junit4]   2>           "leader":"true"}}}}}
   [junit4]   2> 1194386 INFO  (qtp2078206583-20933) [n:127.0.0.1:42919_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=228
   [junit4]   2> 1194389 INFO  (qtp800759684-20925) [n:127.0.0.1:43239_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> 1194486 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
   [junit4]   2> 1194487 INFO  (zkCallback-12122-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> 1194487 INFO  (zkCallback-12100-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> 1194487 INFO  (zkCallback-12100-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> 1194488 INFO  (qtp800759684-20925) [n:127.0.0.1:43239_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:42919_solr&wt=javabin&version=2} status=0 QTime=650
   [junit4]   2> 1194488 INFO  (SUITE-AutoscalingHistoryHandlerTest-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
   [junit4]   2> 1194493 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 1194493 INFO  (OverseerCollectionConfigSetProcessor-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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> 1194493 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
   [junit4]   2> 1194494 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
   [junit4]   2> 1194495 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
   [junit4]   2> 1194497 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1194498 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194500 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194501 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194502 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194503 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194504 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194506 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194507 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194508 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194509 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194510 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194511 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194513 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194514 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194515 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194516 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194517 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194518 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194520 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194521 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194522 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194523 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194525 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194526 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194527 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194530 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194532 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194533 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194534 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194536 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194537 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194539 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194540 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194541 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194543 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194544 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194545 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194548 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194549 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194551 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
   [junit4]   2> 1194552 INFO  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
   [junit4]   2> 1194555 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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:43239_solr",
   [junit4]   2>   "nrtReplicas":"3",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "replicationFactor":"3",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1194556 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
   [junit4]   2> 1194656 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
   [junit4]   2> 1194756 WARN  (OverseerThreadFactory-12108-thread-2-processing-n:127.0.0.1:42919_solr) [n:127.0.0.1:42919_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> 1194760 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1194760 INFO  (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1194763 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1194763 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194763 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1194763 INFO  (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1194765 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194765 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194766 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1194766 INFO  (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1194767 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194767 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node5":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"},
   [junit4]   2>     "core_node6":{
   [junit4]   2>       "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>       "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1194936 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
   [junit4]   2> 1195038 INFO  (qtp800759684-20925) [n:127.0.0.1:43239_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> 1195038 INFO  (qtp800759684-20927) [n:127.0.0.1:43239_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> 1195039 INFO  (qtp800759684-20924) [n:127.0.0.1:43239_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> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_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> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_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> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "force_set_state":"false"}
   [junit4]   2> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_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>           "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>           "type":"NRT"},
   [junit4]   2>         "core_node5":{
   [junit4]   2>           "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>           "node_name":"127.0.0.1:43239_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>           "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":"127.0.0.1:43239_solr",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "core_node_name":"core_node6",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_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> 1195045 DEBUG (OverseerStateUpdate-72544281393496070-127.0.0.1:42919_solr-n_0000000000) [n:127.0.0.1:42919_solr     ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
   [junit4]   2>   "core_node_name":"core_node6",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:43239/solr",
   [junit4]   2>   "node_name":"127.0.0.1:43239_solr",
   

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

, h2c)}{127.0.0.1:0}
   [junit4]   2> 1787241 INFO  (jetty-closer-15730-thread-2) [     ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c9f2dd4{/solr,null,STOPPED}
   [junit4]   2> 1787241 INFO  (jetty-closer-15730-thread-2) [     ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1787242 INFO  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1787242 WARN  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker-SendThread(127.0.0.1:38771)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101baac35cd0000.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x101baac35cd0000, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101baac35cd0000, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 1787417 WARN  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker-SendThread(127.0.0.1:38771)) [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101baac35cd0001.
   [junit4]   2>           => EndOfStreamException: Unable to read additional data from server sessionid 0x101baac35cd0001, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101baac35cd0001, likely server has closed socket
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
   [junit4]   2> 1787519 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> 	12	/solr/aliases.json
   [junit4]   2> 	11	/solr/security.json
   [junit4]   2> 	9	/solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/terms/shard1
   [junit4]   2> 	9	/solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/terms/shard1
   [junit4]   2> 	9	/solr/configs/conf
   [junit4]   2> 	9	/solr/clusterprops.json
   [junit4]   2> 	8	/solr/packages.json
   [junit4]   2> 	8	/solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
   [junit4]   2> 	6	/solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/terms/shard1
   [junit4]   2> 	6	/solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
   [junit4]   2> 	4	/solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
   [junit4]   2> 	2	/solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/collectionprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	65	/solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
   [junit4]   2> 	41	/solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
   [junit4]   2> 	23	/solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
   [junit4]   2> 	12	/solr/clusterstate.json
   [junit4]   2> 	8	/solr/clusterprops.json
   [junit4]   2> 	5	/solr/autoscaling.json
   [junit4]   2> 	2	/solr/overseer_elect/election/72544317814407186-127.0.0.1:45331_solr-n_0000000004
   [junit4]   2> 	2	/solr/overseer_elect/election/72544317814407196-127.0.0.1:35667_solr-n_0000000008
   [junit4]   2> 	2	/solr/overseer_elect/election/72544317814407192-127.0.0.1:44047_solr-n_0000000006
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	91	/solr/overseer/queue
   [junit4]   2> 	87	/solr/live_nodes
   [junit4]   2> 	47	/solr/collections
   [junit4]   2> 	28	/solr/overseer/collection-queue-work
   [junit4]   2> 	5	/solr/autoscaling/events/.scheduled_maintenance
   [junit4]   2> 	5	/solr/autoscaling/events/.auto_add_replicas
   [junit4]   2> 	5	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 1787522 INFO  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38771
   [junit4]   2> 1787522 INFO  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38771
   [junit4]   2> 1787522 INFO  (SUITE-TestPullReplicaErrorHandling-seed#[3904DBBA4F4DFDDC]-worker) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38771
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestPullReplicaErrorHandling_3904DBBA4F4DFDDC-001
   [junit4]   2> Nov 26, 2020 8:53:28 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=Lucene84, fieldName_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=PostingsFormat(name=MockRandom)}, docValues:{}, maxPointsInLeafNode=478, maxMBSortInHeap=6.607658064163492, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=zh-HK, timezone=Asia/Krasnoyarsk
   [junit4]   2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=221277040,total=527433728
   [junit4]   2> NOTE: All tests run in this JVM: [TestGraphTermsQParserPlugin, TestReplicaProperties, SolrTestCaseJ4Test, CloneFieldUpdateProcessorFactoryTest, ExplicitHLLTest, TestSubQueryTransformerCrossCore, ZkStateWriterTest, BooleanFieldTest, TestTextField, TestPostingsSolrHighlighter, AddSchemaFieldsUpdateProcessorFactoryTest, SolrCLIZkUtilsTest, VMParamsZkACLAndCredentialsProvidersTest, BasicZkTest, TestHttpServletCarrier, BlockJoinFacetSimpleTest, TestTolerantUpdateProcessorRandomCloud, TestMinHashQParser, ShardRoutingCustomTest, TestClusterProperties, AnalysisAfterCoreReloadTest, TestJsonRequest, HighlighterConfigTest, TestFieldTypeResource, TestDynamicFieldResource, BlockDirectoryTest, PrimitiveFieldTypeTest, SolrMetricsIntegrationTest, ZookeeperReadAPITest, UninvertDocValuesMergePolicyTest, TestSolrDeletionPolicy1, OverseerSolrResponseTest, TestNestedDocsSort, TestSafeXMLParsing, SuggesterTSTTest, CollectionStateFormat2Test, TestCollectionAPI, MBeansHandlerTest, TestExactStatsCache, ByteBuffersDirectoryFactoryTest, TestNamedUpdateProcessors, TestInPlaceUpdatesStandalone, RootFieldTest, TestReloadAndDeleteDocs, DistributedSuggestComponentTest, XMLAtomicUpdateMultivalueTest, SolrRequestParserTest, TestLuceneMatchVersion, SchemaVersionSpecificBehaviorTest, TestFunctionQuery, TestSimComputePlanAction, IndexSchemaTest, TestStressLiveNodes, TestRecoveryHdfs, SpellingQueryConverterTest, NumberUtilsTest, DistributedExpandComponentTest, CreateCollectionCleanupTest, TestSolrCloudWithKerberosAlt, DistributedVersionInfoTest, TestDistribPackageStore, LeaderElectionContextKeyTest, TestSolr4Spatial2, TestSimpleTrackingShardHandler, TestCollationField, OverriddenZkACLAndCredentialsProvidersTest, TestHdfsUpdateLog, TestFieldCacheSort, SplitHandlerTest, TestSolrConfigHandlerConcurrent, TestReplicationHandler, JavaBinAtomicUpdateMultivalueTest, BasicAuthIntegrationTest, DistanceFunctionTest, TestTestInjection, NodeLostTriggerIntegrationTest, TestFuzzyAnalyzedSuggestions, ReturnFieldsTest, TestNumericTokenStream, StatsComponentTest, HdfsRecoverLeaseTest, ResponseLogComponentTest, SpatialFilterTest, SecurityConfHandlerTest, ResourceLoaderTest, PeerSyncWithBufferUpdatesTest, TestAnalyzedSuggestions, MoveReplicaHDFSFailoverTest, PathHierarchyTokenizerFactoryTest, TestSimNodeLostTrigger, FullSolrCloudDistribCmdsTest, TestConfigSetsAPIZkFailure, ClusterStateMockUtilTest, TestCloudJSONFacetSKGEquiv, TestMacroExpander, HttpSolrCallGetCoreTest, SearchRateTriggerTest, CategoryRoutedAliasUpdateProcessorTest, TestRawResponseWriter, MultiThreadedOCPTest, ChaosMonkeySafeLeaderWithPullReplicasTest, TestLegacyFieldCache, TestDistributedMap, TestBooleanSimilarityFactory, RecoveryZkTest, ZkNodePropsTest, AutoscalingHistoryHandlerTest, OpenExchangeRatesOrgProviderTest, SolrIndexMetricsTest, JavabinLoaderTest, TestRemoteStreaming, RegexBoostProcessorTest, TestBinaryResponseWriter, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestSolrCoreProperties, TestTolerantSearch, TestTrie, TestLuceneIndexBackCompat, BasicDistributedZk2Test, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, DistribDocExpirationUpdateProcessorTest, HttpPartitionWithTlogReplicasTest, RouteFieldTest, SSLMigrationTest, SaslZkACLProviderTest, SyncSliceTest, TestPullReplicaErrorHandling]
   [junit4] Completed [538/921 (2!)] on J0 in 42.78s, 4 tests, 1 error <<< FAILURES!

[...truncated 51949 lines...]