You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/02/20 20:33:48 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 310 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/310/
1 tests failed.
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
action did not execute
Stack Trace:
java.lang.AssertionError: action did not execute
at __randomizedtesting.SeedInfo.seed([5BB9DFC3FC2787FC:36457B3E466F78FB]: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 15641 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 1897297 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1897298 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/data-dir-149-001
[junit4] 2> 1897299 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1897299 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1897301 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1897301 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1897302 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/tempDir-001
[junit4] 2> 1897302 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1897302 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1897302 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1897303 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1897402 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 39841
[junit4] 2> 1897403 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39841
[junit4] 2> 1897403 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39841
[junit4] 2> 1897403 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39841
[junit4] 2> 1897407 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1897413 INFO (zkConnectionManagerCallback-17269-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1897414 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1897430 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1897431 INFO (zkConnectionManagerCallback-17271-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1897431 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1897432 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1897433 INFO (zkConnectionManagerCallback-17273-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1897433 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1897435 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker-SendThread(127.0.0.1:39841)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10377fb31380002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10377fb31380002, 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 0x10377fb31380002, 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> 1897546 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1897546 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1897546 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1897551 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1897552 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67576d21{/solr,null,AVAILABLE}
[junit4] 2> 1897558 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1897558 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1897558 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1897559 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3fa28d0a{/solr,null,AVAILABLE}
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a82d7c{SSL, (ssl, http/1.1)}{127.0.0.1:38609}
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.e.j.s.Server Started @1897588ms
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:39841/solr, hostPort=38609}
[junit4] 2> 1897560 ERROR (jetty-launcher-17274-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.1
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1897560 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-20T20:07:39.523Z
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16cf880{SSL, (ssl, http/1.1)}{127.0.0.1:44843}
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.e.j.s.Server Started @1897589ms
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:39841/solr, hostPort=44843}
[junit4] 2> 1897561 ERROR (jetty-launcher-17274-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.1
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1897561 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-20T20:07:39.524Z
[junit4] 2> 1897562 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1897563 INFO (zkConnectionManagerCallback-17276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1897622 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1897623 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1897624 INFO (zkConnectionManagerCallback-17278-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1897624 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1897628 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1897628 WARN (jetty-launcher-17274-thread-2-SendThread(127.0.0.1:39841)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10377fb31380003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10377fb31380003, 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 0x10377fb31380003, 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> 1897732 WARN (jetty-launcher-17274-thread-1-SendThread(127.0.0.1:39841)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10377fb31380004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10377fb31380004, 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 0x10377fb31380004, 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> 1898637 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1898638 WARN (jetty-launcher-17274-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1898639 WARN (jetty-launcher-17274-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3dc2f10e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1898639 WARN (jetty-launcher-17274-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3dc2f10e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1898641 WARN (jetty-launcher-17274-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1898643 WARN (jetty-launcher-17274-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6a4ae498[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1898643 WARN (jetty-launcher-17274-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6a4ae498[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1898645 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39841/solr
[junit4] 2> 1898647 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1898649 INFO (zkConnectionManagerCallback-17292-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1898649 INFO (jetty-launcher-17274-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1898650 WARN (jetty-launcher-17274-thread-1-SendThread(127.0.0.1:39841)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10377fb31380005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10377fb31380005, 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 0x10377fb31380005, 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> 1898751 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1898752 INFO (zkConnectionManagerCallback-17294-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1898752 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1898849 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44843_solr
[junit4] 2> 1898849 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer Overseer (id=73033939714310150-127.0.0.1:44843_solr-n_0000000000) starting
[junit4] 2> 1898857 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44843_solr
[junit4] 2> 1898857 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44843_solr
[junit4] 2> 1898863 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1898865 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1898865 WARN (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_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> 1898870 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1898877 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1898877 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1898877 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1898878 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1898955 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:44843_solr]
[junit4] 2> 1898964 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1898965 DEBUG (OverseerAutoScalingTriggerThread-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1898965 DEBUG (ScheduledTrigger-17299-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> 1898987 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44843.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1898994 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44843.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1898994 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44843.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1898995 INFO (jetty-launcher-17274-thread-1) [n:127.0.0.1:44843_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/tempDir-001/node1
[junit4] 2> 1899987 DEBUG (ScheduledTrigger-17299-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1899999 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1900000 WARN (jetty-launcher-17274-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1900007 WARN (jetty-launcher-17274-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7ab59de1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1900007 WARN (jetty-launcher-17274-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7ab59de1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1900010 WARN (jetty-launcher-17274-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1900011 WARN (jetty-launcher-17274-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fb0191a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1900011 WARN (jetty-launcher-17274-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fb0191a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1900012 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39841/solr
[junit4] 2> 1900015 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1900016 INFO (zkConnectionManagerCallback-17314-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1900016 INFO (jetty-launcher-17274-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1900292 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1900295 INFO (zkConnectionManagerCallback-17316-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1900295 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1900309 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1900313 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38609_solr as DOWN
[junit4] 2> 1900313 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1900313 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38609_solr
[junit4] 2> 1900314 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 1900314 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38609_solr
[junit4] 2> 1900314 INFO (zkCallback-17293-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1900316 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1900316 WARN (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_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> 1900320 INFO (zkCallback-17315-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1900327 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1900346 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1900355 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1900355 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38609.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1900356 INFO (jetty-launcher-17274-thread-2) [n:127.0.0.1:38609_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/tempDir-001/node2
[junit4] 2> 1900375 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1900379 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1900380 INFO (zkConnectionManagerCallback-17329-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1900381 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1900388 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1900393 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39841/solr ready
[junit4] 2> 1900455 INFO (OverseerThreadFactory-17301-thread-1-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1900458 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_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:44843_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1900459 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 1900459 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 1900634 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1900634 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1900635 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1900635 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr"}}}
[junit4] 2> 1900735 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 1900960 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900960 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1900966 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/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> 1900966 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/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> 1900966 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1900966 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1900973 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.1
[junit4] 2> 1900978 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900980 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900980 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900982 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900984 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900984 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1900986 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44843.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@767a104
[junit4] 2> 1900986 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 1900987 DEBUG (ScheduledTrigger-17299-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1901047 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901047 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901048 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901048 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1901050 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901050 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901050 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901050 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901051 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901051 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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 1692246121396568064
[junit4] 2> 1901055 INFO (searcherExecutor-17331-thread-1-processing-n:127.0.0.1:44843_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:44843_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> 1901058 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901058 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901063 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901063 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901063 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44843/solr/.system_shard1_replica_n1/
[junit4] 2> 1901063 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901064 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:44843/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 1901064 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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/73033939714310150-core_node2-n_0000000000
[junit4] 2> 1901066 INFO (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44843/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 1901067 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 1901067 INFO (zkCallback-17293-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> 1901067 INFO (zkCallback-17293-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> 1901068 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"leader"} current state version: 0
[junit4] 2> 1901169 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 1901170 INFO (zkCallback-17293-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> 1901170 INFO (zkCallback-17293-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> 1901170 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901173 ERROR (qtp194286576-32132) [n:127.0.0.1:44843_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Collection .system is not lazy or watched!
[junit4] 2> 1901177 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/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> 1901177 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/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> 1901177 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1901177 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_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> "node_name":"127.0.0.1:44843_solr",
[junit4] 2> "base_url":"https://127.0.0.1:44843/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1901188 INFO (qtp194286576-32132) [n:127.0.0.1:44843_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> 1901190 INFO (qtp189810188-32123) [n:127.0.0.1:38609_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> 1901356 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 1901357 INFO (zkCallback-17315-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> 1901357 INFO (zkCallback-17293-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> 1901357 INFO (zkCallback-17293-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1901357 INFO (zkCallback-17293-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> 1901358 INFO (qtp189810188-32123) [n:127.0.0.1:38609_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:44843_solr&wt=javabin&version=2} status=0 QTime=928
[junit4] 2> 1901358 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 1901373 INFO (OverseerCollectionConfigSetProcessor-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_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> 1901373 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1901374 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 1901375 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 1901376 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 1901377 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 1901379 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901381 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901382 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901383 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901384 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901385 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901387 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901388 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901389 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901391 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901392 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901393 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901395 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901396 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901397 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901398 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901400 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901401 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901402 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901404 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901405 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901406 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901407 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901408 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901409 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901411 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901413 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901415 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901416 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901417 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901418 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901419 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901420 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901422 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901431 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901435 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901436 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901437 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901438 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901439 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 1901440 INFO (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 1901443 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_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:38609_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1901443 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1901547 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 1901676 WARN (OverseerThreadFactory-17301-thread-2-processing-n:127.0.0.1:44843_solr) [n:127.0.0.1:44843_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> 1901680 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1901680 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1901682 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1901682 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}
[junit4] 2> 1901683 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1901683 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1901685 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}
[junit4] 2> 1901685 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}
[junit4] 2> 1901686 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1901686 INFO (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1901696 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}
[junit4] 2> 1901696 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}
[junit4] 2> 1901851 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 1901895 INFO (qtp189810188-32126) [n:127.0.0.1:38609_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> 1901896 INFO (qtp189810188-32122) [n:127.0.0.1:38609_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> 1901899 INFO (qtp189810188-32123) [n:127.0.0.1:38609_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> 1901901 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/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> 1901901 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/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> 1901901 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1901901 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr"}}}}}
[junit4] 2> 1901905 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/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> 1901905 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/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> 1901905 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1901905 DEBUG (OverseerStateUpdate-73033939714310150-127.0.0.1:44843_solr-n_0000000000) [n:127.0.0.1:44843_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:38609_solr",
[junit4] 2> "base_url":"https://127.0.0.1:38609/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[...truncated too long message...]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1966148 INFO (closeThreadPool-17398-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73033939714310150-127.0.0.1:44843_solr-n_0000000000) closing
[junit4] 2> 1966208 INFO (jetty-closer-17381-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1a82d7c{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1966208 INFO (jetty-closer-17381-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@67576d21{/solr,null,STOPPED}
[junit4] 2> 1966208 INFO (jetty-closer-17381-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1966355 INFO (jetty-closer-17381-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73033939714310150-127.0.0.1:44843_solr-n_0000000000) closing
[junit4] 2> 1966367 INFO (jetty-closer-17381-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@16cf880{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1966367 INFO (jetty-closer-17381-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3fa28d0a{/solr,null,STOPPED}
[junit4] 2> 1966367 INFO (jetty-closer-17381-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1966371 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1966373 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker-SendThread(127.0.0.1:39841)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10377fb31380000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10377fb31380000, 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 0x10377fb31380000, 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> 1966711 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/.system/terms/shard1
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 18 /solr/collections/.system/state.json
[junit4] 2> 12 /solr/autoscaling.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 36 /solr/overseer/queue
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 11 /solr/collections
[junit4] 2> 9 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> 1966716 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:39841
[junit4] 2> 1966716 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39841
[junit4] 2> 1966716 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[5BB9DFC3FC2787FC]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39841
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_5BB9DFC3FC2787FC-001
[junit4] 2> Feb 20, 2021 8:08:48 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {action_s=FST50, event.property.preferredOperation_ss=PostingsFormat(name=MockRandom), event.source_s=PostingsFormat(name=LuceneFixedGap), event.property.replicaType_ss=FST50, type=FST50, before.actions_ss=PostingsFormat(name=LuceneFixedGap), event.property._enqueue_time__ss=FST50, error.details_t=PostingsFormat(name=LuceneFixedGap), stage_s=FST50, event.id_s=Lucene84, event.property.eventTimes_ss=FST50, source_s=Lucene84, event.type_s=FST50, id=Lucene84, event.property.nodeNames_ss=FST50, error.message_t=FST50}, docValues:{_version_=DocValuesFormat(name=Lucene80), event.time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=954, maxMBSortInHeap=6.944011373393754, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ga-IE, timezone=Asia/Jayapura
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=160745552,total=520617984
[junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest, SystemCollectionCompatTest, TestSolrDeletionPolicy1, DistributedExpandComponentTest, CoreAdminRequestStatusTest, FieldAnalysisRequestHandlerTest, DateMathParserTest, ClusterStateUpdateTest, UninvertDocValuesMergePolicyTest, TestExceedMaxTermLength, FuzzySearchTest, TestRemoteStreaming, TestInPlaceUpdatesRequiredField, ReturnFieldsTest, ExplicitHLLTest, ImplicitSnitchTest, TestWordDelimiterFilterFactory, TestHttpServletCarrier, TlogReplayBufferedWhileIndexingTest, ZkCLITest, TestRestManager, TestDFRSimilarityFactory, TestSortByMinMaxFunction, ConvertedLegacyTest, TestUtilizeNode, VersionInfoTest, DeleteShardTest, MoreLikeThisComponentTest, SplitHandlerTest, AuditLoggerPluginTest, URLClassifyProcessorTest, TestGraphMLResponseWriter, TestRestoreCore, PeerSyncTest, TaggerTest, TriggerIntegrationTest, TestOnReconnectListenerSupport, TestQueryingOnDownCollection, TestCloudNestedDocsSort, SSLMigrationTest, HdfsRecoveryZkTest, TestTextField, TestOverriddenPrefixQueryForCustomFieldType, TestChildDocTransformer, TestSchemaVersionResource, TestDynamicLoadingUrl, TestSimDistribStateManager, ShardSplitTest, TestCollectionAPIs, BlockJoinFacetRandomTest, ConcurrentCreateCollectionTest, TestRecoveryHdfs, TestUpdate, SolrSlf4jReporterTest, TestTlogReplayVsRecovery, TestHttpShardHandlerFactory, MetricsDisabledCloudTest, DirectoryFactoryTest, TestReloadAndDeleteDocs, BooleanFieldTest, SolrMetricsIntegrationTest, DistributedFacetPivotSmallAdvancedTest, OverseerStatusTest, TestAnalyzeInfixSuggestions, TestBinaryField, ScheduledTriggerIntegrationTest, TestJsonFacetsStatsParsing, LeaderVoteWaitTimeoutTest, TestRequestForwarding, TestFieldCacheReopen, ComputePlanActionTest, TestExternalRoleRuleBasedAuthorizationPlugin, EchoParamsTest, TestRangeQuery, DateFieldTest, DistributedQueryComponentCustomSortTest, JavabinLoaderTest, DistribJoinFromCollectionTest, TestFieldTypeResource, CategoryRoutedAliasUpdateProcessorTest, HighlighterConfigTest, TestBinaryResponseWriter, TestRawResponseWriter, TestUninvertingReader, TestInitParams, TestLeaderElectionWithEmptyReplica, SolrIndexConfigTest, HttpPartitionTest, TestLegacyBM25SimilarityFactory, HdfsChaosMonkeyNothingIsSafeTest, SpatialFilterTest, TestConfigReload, BasicDistributedZk2Test, SystemLogListenerTest, OverseerModifyCollectionTest, DocExpirationUpdateProcessorFactoryTest, PeerSyncReplicationTest, BlockCacheTest, TestDocSet, ZkShardTermsTest, SubstringBytesRefFilterTest, QueryResultKeyTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, FileBasedSpellCheckerTest, SpellPossibilityIteratorTest, TestSearcherReuse, TestQueryTypes, CollectionReloadTest, TestCoreDiscovery, TestMinimalConfig, CustomHighlightComponentTest, DirectSolrConnectionTest, TestCharFilters, LeaderFailoverAfterPartitionTest, MultiThreadedOCPTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, RestartWhileUpdatingTest, SaslZkACLProviderTest, ShardRoutingCustomTest, ShardRoutingTest, ShardTermsTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudPivotFacet, TestCloudSearcherWarming, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestLeaderElectionZkExpiry, TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens, TestSolrCloudWithSecureImpersonation, SimpleCollectionCreateDeleteTest, TestHdfsCloudBackupRestore, TestRequestStatusCollectionAPI, IndexSizeTriggerTest, MetricTriggerIntegrationTest, NodeAddedTriggerTest, NodeMarkersRegistrationTest, TestSimTriggerIntegration, CdcrOpsAndBoundariesTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, RulesTest, ResourceLoaderTest, SolrCoreCheckLockOnStartupTest, TestCodecSupport, TestConfig, TestConfigOverlay, TestConfigSetImmutable, TestNRTOpen, TestSolrXml, TestXIncludeConfig, TestDistribPackageStore, TestHdfsBackupRestoreCore, TestStressThreadBackup, TestSystemCollAutoCreate, V2StandaloneTest, XmlUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest]
[junit4] Completed [683/923 (1!)] on J2 in 70.53s, 1 test, 1 failure <<< FAILURES!
[...truncated 51484 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 311 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/311/
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderTragicEventTest.testLeaderFailsOver
Error Message:
Error from server at https://127.0.0.1:35711/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:35711/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
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:1171)
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.LeaderTragicEventTest.testLeaderFailsOver(LeaderTragicEventTest.java:80)
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 14947 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderTragicEventTest
[junit4] 2> 1213884 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1213884 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1213884 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/data-dir-104-001
[junit4] 2> 1213884 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1213886 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1213886 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1213887 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001
[junit4] 2> 1213887 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1213887 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1213887 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1213888 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1213987 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 36571
[junit4] 2> 1213987 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36571
[junit4] 2> 1213987 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36571
[junit4] 2> 1213987 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36571
[junit4] 2> 1213988 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1213991 INFO (zkConnectionManagerCallback-14569-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1213991 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1213993 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1213996 INFO (zkConnectionManagerCallback-14571-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1213996 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1213996 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1213997 INFO (zkConnectionManagerCallback-14573-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1213997 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1214102 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1214103 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@461a42e3{/solr,null,AVAILABLE}
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ea6de06{/solr,null,AVAILABLE}
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@22bdee70{SSL, (ssl, http/1.1)}{127.0.0.1:35711}
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.e.j.s.Server Started @1214141ms
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36571/solr, hostPort=35711}
[junit4] 2> 1214104 ERROR (jetty-launcher-14574-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.1
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1214104 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-20T22:01:43.337Z
[junit4] 2> 1214105 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1214105 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6af3546{SSL, (ssl, http/1.1)}{127.0.0.1:36929}
[junit4] 2> 1214105 INFO (jetty-launcher-14574-thread-1) [ ] o.e.j.s.Server Started @1214143ms
[junit4] 2> 1214105 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36571/solr, hostPort=36929}
[junit4] 2> 1214105 ERROR (jetty-launcher-14574-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1214105 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.1
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-20T22:01:43.339Z
[junit4] 2> 1214106 INFO (zkConnectionManagerCallback-14576-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1214106 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1214107 INFO (zkConnectionManagerCallback-14578-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1214107 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1214107 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1214107 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1214110 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1214111 WARN (jetty-launcher-14574-thread-2-SendThread(127.0.0.1:36571)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10378616c120003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10378616c120003, 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 0x10378616c120003, 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> 1214113 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1214212 WARN (jetty-launcher-14574-thread-1-SendThread(127.0.0.1:36571)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10378616c120004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10378616c120004, 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 0x10378616c120004, 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> 1214968 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1214969 WARN (jetty-launcher-14574-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1214969 WARN (jetty-launcher-14574-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6033874d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1214969 WARN (jetty-launcher-14574-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6033874d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1214972 WARN (jetty-launcher-14574-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1214972 WARN (jetty-launcher-14574-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3275d177[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1214972 WARN (jetty-launcher-14574-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3275d177[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1214973 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36571/solr
[junit4] 2> 1214975 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1214979 INFO (zkConnectionManagerCallback-14592-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1214979 INFO (jetty-launcher-14574-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1215086 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1215087 INFO (zkConnectionManagerCallback-14594-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1215089 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1215167 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35711_solr
[junit4] 2> 1215168 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.Overseer Overseer (id=73034378788339718-127.0.0.1:35711_solr-n_0000000000) starting
[junit4] 2> 1215173 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35711_solr
[junit4] 2> 1215173 INFO (OverseerStateUpdate-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35711_solr
[junit4] 2> 1215175 INFO (OverseerStateUpdate-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1215176 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1215177 WARN (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_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> 1215191 INFO (jetty-launcher-14574-thread-2) [n:127.0.0.1:35711_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node2
[junit4] 2> 1215404 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1215404 WARN (jetty-launcher-14574-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1215405 WARN (jetty-launcher-14574-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59976e46[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1215405 WARN (jetty-launcher-14574-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59976e46[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1215407 WARN (jetty-launcher-14574-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1215410 WARN (jetty-launcher-14574-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3586013c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1215410 WARN (jetty-launcher-14574-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3586013c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1215411 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36571/solr
[junit4] 2> 1215412 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1215412 INFO (zkConnectionManagerCallback-14612-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1215412 INFO (jetty-launcher-14574-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1215514 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1215515 INFO (zkConnectionManagerCallback-14614-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1215515 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1215519 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1215522 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36929_solr as DOWN
[junit4] 2> 1215523 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1215523 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36929_solr
[junit4] 2> 1215524 INFO (zkCallback-14593-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1215525 INFO (zkCallback-14613-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1215525 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1215526 WARN (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_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> 1215541 INFO (jetty-launcher-14574-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1
[junit4] 2> 1215550 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1215551 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1215555 INFO (zkConnectionManagerCallback-14625-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1215555 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1215556 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1215557 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36571/solr ready
[junit4] 2> 1215575 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOtherReplicasAreNotActive
[junit4] 2> 1215619 INFO (OverseerThreadFactory-14601-thread-1-processing-n:127.0.0.1:35711_solr) [n:127.0.0.1:35711_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testOtherReplicasAreNotActive
[junit4] 2> 1215726 INFO (OverseerStateUpdate-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35711_solr",
[junit4] 2> "base_url":"https://127.0.0.1:35711/solr",
[junit4] 2> "collection":"testOtherReplicasAreNotActive",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1215728 INFO (OverseerStateUpdate-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:36929_solr",
[junit4] 2> "base_url":"https://127.0.0.1:36929/solr",
[junit4] 2> "collection":"testOtherReplicasAreNotActive",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1215936 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n1&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1215937 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1215951 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.1
[junit4] 2> 1215964 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1215965 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1215965 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n1' using configuration from configset config, trusted=true
[junit4] 2> 1215965 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node2/testOtherReplicasAreNotActive_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node2/testOtherReplicasAreNotActive_shard1_replica_n1/data/]
[junit4] 2> 1215983 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n2&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1215999 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1215999 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1215999 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1215999 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1216006 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1216007 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1216007 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1216007 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692253295990538240
[junit4] 2> 1216011 INFO (searcherExecutor-14627-thread-1-processing-n:127.0.0.1:35711_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 c:testOtherReplicasAreNotActive s:shard1 r:core_node3) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1216018 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.1
[junit4] 2> 1216025 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1216027 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testOtherReplicasAreNotActive/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1216027 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testOtherReplicasAreNotActive/leaders/shard1
[junit4] 2> 1216027 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1216028 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n2' using configuration from configset config, trusted=true
[junit4] 2> 1216028 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2/data/]
[junit4] 2> 1216031 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoute in=9999ms
[junit4] 2> 1216075 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1216075 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1216077 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1216077 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1216078 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1216078 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1216078 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1216079 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692253296066035712
[junit4] 2> 1216081 INFO (searcherExecutor-14633-thread-1-processing-n:127.0.0.1:36929_solr x:testOtherReplicasAreNotActive_shard1_replica_n2 c:testOtherReplicasAreNotActive s:shard1 r:core_node4) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1216089 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testOtherReplicasAreNotActive/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 1216089 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testOtherReplicasAreNotActive/leaders/shard1
[junit4] 2> 1216532 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1216532 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1216532 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:35711/solr/testOtherReplicasAreNotActive_shard1_replica_n1/
[junit4] 2> 1216533 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testOtherReplicasAreNotActive_shard1_replica_n1 url=https://127.0.0.1:35711/solr START replicas=[https://127.0.0.1:36929/solr/testOtherReplicasAreNotActive_shard1_replica_n2/] nUpdates=100
[junit4] 2> 1216533 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testOtherReplicasAreNotActive_shard1_replica_n1 url=https://127.0.0.1:35711/solr DONE. We have no versions. sync failed.
[junit4] 2> 1216539 INFO (qtp1230358567-23806) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.S.Request [testOtherReplicasAreNotActive_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1216540 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1216540 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1216540 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testOtherReplicasAreNotActive/leaders/shard1/leader after winning as /collections/testOtherReplicasAreNotActive/leader_elect/shard1/election/73034378788339718-core_node3-n_0000000000
[junit4] 2> 1216542 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:35711/solr/testOtherReplicasAreNotActive_shard1_replica_n1/ shard1
[junit4] 2> 1216644 INFO (zkCallback-14593-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1216645 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1216648 INFO (qtp1833371539-23802) [n:127.0.0.1:35711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n1&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=711
[junit4] 2> 1216748 INFO (zkCallback-14593-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1216748 INFO (zkCallback-14593-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1217099 INFO (qtp1230358567-23801) [n:127.0.0.1:36929_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n2&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1116
[junit4] 2> 1217101 INFO (qtp1833371539-23798) [n:127.0.0.1:35711_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> 1217199 INFO (zkCallback-14613-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1217199 INFO (zkCallback-14593-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1217199 INFO (zkCallback-14593-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1217199 INFO (zkCallback-14613-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1217200 INFO (qtp1833371539-23798) [n:127.0.0.1:35711_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=config&name=testOtherReplicasAreNotActive&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1585
[junit4] 2> 1217200 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testOtherReplicasAreNotActive
[junit4] 2> 1217205 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.LeaderTragicEventTest Stop jetty node : https://127.0.0.1:36929/solr state:DocCollection(testOtherReplicasAreNotActive//collections/testOtherReplicasAreNotActive/state.json/5 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35711_solr",
[junit4] 2> "base_url":"https://127.0.0.1:35711/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:36929_solr",
[junit4] 2> "base_url":"https://127.0.0.1:36929/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0"}
[junit4] 2> 1217205 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=154758643
[junit4] 2> 1217205 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36929_solr
[junit4] 2> 1217205 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1217205 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:36929_solr as DOWN
[junit4] 2> 1217207 INFO (coreCloseExecutor-14641-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@2fcc548c
[junit4] 2> 1217207 INFO (coreCloseExecutor-14641-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testOtherReplicasAreNotActive.shard1.replica_n2 tag=SolrCore@2fcc548c
[junit4] 2> 1217207 INFO (coreCloseExecutor-14641-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testOtherReplicasAreNotActive.shard1.leader tag=SolrCore@2fcc548c
[junit4] 2> 1217208 INFO (coreCloseExecutor-14641-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1217209 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1217209 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1217209 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1217209 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1217210 WARN (jetty-launcher-14574-thread-1-SendThread(127.0.0.1:36571)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10378616c120008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10378616c120008, 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 0x10378616c120008, 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> 1217210 INFO (zkCallback-14593-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1217211 INFO (zkCallback-14624-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1217308 INFO (zkCallback-14593-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1217308 INFO (zkCallback-14593-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1217312 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6af3546{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1217312 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5ea6de06{/solr,null,STOPPED}
[junit4] 2> 1217313 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1217313 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 36929
[junit4] 2> 1217313 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.SolrCloudTestCase waitForState (testOtherReplicasAreNotActive): Timeout waiting for replica get down
[junit4] 2> 1217316 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.LeaderTragicEventTest Will crash leader : core_node3:{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35711_solr",
[junit4] 2> "base_url":"https://127.0.0.1:35711/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1217324 ERROR (qtp1833371539-23800) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Error closing IndexWriter
[junit4] 2> => java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot flush
[junit4] 2> at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3895)
[junit4] 2> java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot flush
[junit4] 2> at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3895) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.flush(IndexWriter.java:3887) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1235) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1278) ~[java/:?]
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.close(SolrIndexWriter.java:298) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.changeWriter(DefaultSolrCoreState.java:209) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.newIndexWriter(DefaultSolrCoreState.java:233) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.checkTragicException(CoreContainer.java:2215) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:229) ~[java/:?]
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2646) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) ~[jetty-rewrite-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:716) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) ~[jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556) [jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) [jetty-server-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773) [jetty-util-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905) [jetty-util-9.4.34.v20201102.jar:9.4.34.v20201102]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> Caused by: java.lang.Exception: injected tragedy
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:364) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210) ~[java/:?]
[junit4] 2> ... 40 more
[junit4] 2> 1217326 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1217326 ERROR (qtp1833371539-23800) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Random tragedy fail
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:374)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2646)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:716)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1217326 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.S.Request [testOtherReplicasAreNotActive_shard1_replica_n1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} status=500 QTime=3
[junit4] 2> 1217326 ERROR (qtp1833371539-23800) [n:127.0.0.1:35711_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.HttpSolrCall org.apache.solr.common.SolrException: Random tragedy fail
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:374)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2646)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:716)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1217329 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=36929)
[junit4] 2> 1217329 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 36929 try number 2 ...
[junit4] 2> 1217329 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1217330 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1217330 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1217330 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1217330 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e83a7aa{/solr,null,AVAILABLE}
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@35f8053e{SSL, (ssl, http/1.1)}{127.0.0.1:36929}
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.s.Server Started @1217368ms
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36571/solr, hostPort=36929}
[junit4] 2> 1217331 ERROR (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.1
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 1217331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-02-20T22:01:46.564Z
[junit4] 2> 1217332 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1217332 INFO (zkConnectionManagerCallback-14645-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1217333 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1217333 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1217336 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1217613 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1217613 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1217614 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2ad59697[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1217614 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2ad59697[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1217617 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1217617 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@69c3e587[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1217617 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@69c3e587[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1217619 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36571/solr
[junit4] 2> 1217619 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1217620 INFO (zkConnectionManagerCallback-14656-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1217620 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1217621 INFO (OverseerCollectionConfigSetProcessor-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_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> 1217722 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1217723 INFO (zkConnectionManagerCallback-14658-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1217723 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1217727 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1217731 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36929_solr as DOWN
[junit4] 2> 1217731 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1217731 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36929_solr
[junit4] 2> 1217733 INFO (zkCallback-14624-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1217733 INFO (zkCallback-14593-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1217733 INFO (zkCallback-14657-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1217735 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1217735 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_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> 1217750 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1
[junit4] 2> 1217750 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [n:127.0.0.1:36929_solr ] o.a.s.c.CorePropertiesLocator Cores are: [testOtherReplicasAreNotActive_shard1_replica_n2]
[junit4] 2> 1217759 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.1
[junit4] 2> 1217766 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1217766 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1217775 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.1
[junit4] 2> 1217782 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1217783 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1217783 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n2' using configuration from configset config, trusted=true
[junit4] 2> 1217783 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2/data/]
[junit4] 2> 1217808 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1217808 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1217808 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1217808 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1217809 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1217809 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1217809 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1217809 INFO (coreLoadExecutor-14664-thread-1-processing-n:127.0.0.1:36929_solr) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1692253297880072192
[junit4] 2> 1217811 INFO (searcherExecutor-14666-thread-1-processing-n:127.0.0.1:36929_solr x:testOtherReplicasAreNotActive_shard1_replica_n2 c:testOtherReplicasAreNotActive s:shard1 r:core_node4) [n:127.0.0.1:36929_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1217811 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.c.MiniSolrCloudCluster waitForNode: 127.0.0.1:36929_solr
[junit4] 2> 1217812 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOtherReplicasAreNotActive
[junit4] 2> 1217815 INFO (OverseerThreadFactory-14601-thread-2-processing-n:127.0.0.1:35711_solr) [n:127.0.0.1:35711_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 1217817 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testOtherReplicasAreNotActive.shard1.replica_n1 tag=null
[junit4] 2> 1217817 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4b51fcb5
[junit4] 2> 1217817 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testOtherReplicasAreNotActive.shard1.replica_n1 tag=SolrCore@4b51fcb5
[junit4] 2> 1217817 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testOtherReplicasAreNotActive.shard1.leader tag=SolrCore@4b51fcb5
[junit4] 2> 1217817 INFO (qtp1833371539-23800) [n:127.0.0.1:35711_solr ] o.a.s.u.Di
[...truncated too long message...]
olrCore@285fcff7
[junit4] 2> 1228772 INFO (coreCloseExecutor-14685-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testOtherReplicasAreNotActive.shard1.leader tag=SolrCore@285fcff7
[junit4] 2> 1228773 INFO (coreCloseExecutor-14685-thread-1) [n:127.0.0.1:36929_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1228775 INFO (jetty-closer-14677-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1228775 INFO (jetty-closer-14677-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1228775 INFO (jetty-closer-14677-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1228776 INFO (closeThreadPool-14686-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73034378788339718-127.0.0.1:35711_solr-n_0000000000) closing
[junit4] 2> 1228776 INFO (jetty-closer-14677-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1228776 INFO (OverseerStateUpdate-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [n:127.0.0.1:35711_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35711_solr
[junit4] 2> 1228777 INFO (zkCallback-14657-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1228778 INFO (OverseerAutoScalingTriggerThread-73034378788339718-127.0.0.1:35711_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1228779 INFO (closeThreadPool-14686-thread-2) [ ] o.a.s.c.Overseer Overseer (id=73034378788339718-127.0.0.1:35711_solr-n_0000000000) closing
[junit4] 2> 1228779 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[93EF91C18FB11F7]-SendThread(127.0.0.1:36571)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10378616c12000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10378616c12000c, 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 0x10378616c12000c, 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> 1228877 INFO (jetty-closer-14677-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73034378788339718-127.0.0.1:35711_solr-n_0000000000) closing
[junit4] 2> 1228878 INFO (jetty-closer-14677-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@22bdee70{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1228879 INFO (jetty-closer-14677-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@461a42e3{/solr,null,STOPPED}
[junit4] 2> 1228879 INFO (jetty-closer-14677-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1229333 INFO (jetty-closer-14677-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@35f8053e{SSL, (ssl, http/1.1)}{127.0.0.1:36929}
[junit4] 2> 1229334 INFO (jetty-closer-14677-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6e83a7aa{/solr,null,STOPPED}
[junit4] 2> 1229334 INFO (jetty-closer-14677-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1229334 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1229537 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> 8 /solr/collections/testOtherReplicasAreNotActive/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/collections/testLeaderFailsOver/state.json
[junit4] 2> 4 /solr/configs/config
[junit4] 2> 3 /solr/collections/testLeaderFailsOver/terms/shard1
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/testOtherReplicasAreNotActive/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/collections/testOtherReplicasAreNotActive/state.json
[junit4] 2> 4 /solr/collections/testLeaderFailsOver/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/73034378788339718-127.0.0.1:35711_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 39 /solr/overseer/queue
[junit4] 2> 17 /solr/overseer/collection-queue-work
[junit4] 2> 16 /solr/collections
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 12 /solr/collections/testOtherReplicasAreNotActive/state.json
[junit4] 2> 4 /solr/collections/testLeaderFailsOver/state.json
[junit4] 2>
[junit4] 2> 1229539 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:36571
[junit4] 2> 1229539 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36571
[junit4] 2> 1229539 INFO (SUITE-LeaderTragicEventTest-seed#[93EF91C18FB11F7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36571
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_93EF91C18FB11F7-001
[junit4] 2> Feb 20, 2021 10:01:58 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=624, maxMBSortInHeap=5.926553547540227, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=mk-MK, timezone=America/Atikokan
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=146066256,total=523239424
[junit4] 2> NOTE: All tests run in this JVM: [ClusterStateTest, TestSolr4Spatial, AssignBackwardCompatibilityTest, NodeLostTriggerIntegrationTest, VMParamsZkACLAndCredentialsProvidersTest, TestScoreJoinQPScore, FileUtilsTest, TestHashPartitioner, TestSolrCoreProperties, TestSortableTextField, CdcrBootstrapTest, TestSimComputePlanAction, TestCollapseQParserPlugin, TestReqParamsAPI, HttpPartitionOnCommitTest, UtilsToolTest, TestCrossCoreJoin, IndexSchemaRuntimeFieldTest, OverseerSolrResponseUnsafeSerializationTest, TestFieldCacheVsDocValues, TestHdfsBackupRestoreCore, TestSimExtremeIndexing, CollectionsAPIDistributedZkTest, TestRebalanceLeaders, TestZkChroot, SynonymTokenizerTest, TestLegacyNumericUtils, SimpleFacetsTest, TestManagedResourceStorage, DistributedFacetPivotLongTailTest, TestRandomCollapseQParserPlugin, HdfsDirectoryTest, TestMaxScoreQueryParser, HttpTriggerListenerTest, TestSolrCloudWithKerberosAlt, TestExportWriter, RoutingToNodesWithPropertiesTest, UUIDFieldTest, DistributedUpdateProcessorTest, BlockJoinFacetDistribTest, V2ApiIntegrationTest, DocumentAnalysisRequestHandlerTest, CacheHeaderTest, DirectoryFactoriesTest, TestSlowCompositeReaderWrapper, ChaosMonkeyNothingIsSafeTest, CollectionsAPISolrJTest, FastVectorHighlighterTest, IgnoreLargeDocumentProcessorFactoryTest, WordBreakSolrSpellCheckerTest, TestPhraseSuggestions, TestPseudoReturnFields, BasicFunctionalityTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, DistributedDebugComponentTest, AtomicUpdatesTest, DistributedSpellCheckComponentTest, TestDistributedTracing, CurrencyFieldTypeTest, TestDFISimilarityFactory, SpellCheckCollatorWithCollapseTest, TestFileDictionaryLookup, TestDocBasedVersionConstraints, ZkNodePropsTest, StressHdfsTest, CoreAdminOperationTest, SimplePostToolTest, TestCustomSort, DistributedFacetSimpleRefinementLongTailTest, TestQuerySenderNoQuery, TestExportTool, MergeStrategyTest, TestRTimerTree, CdcrVersionReplicationTest, RankQueryTest, ReindexCollectionTest, HdfsThreadLeakTest, MetricsConfigTest, CursorMarkTest, JWTVerificationkeyResolverTest, TestSimNodeLostTrigger, SuggesterWFSTTest, TestNumericRangeQuery64, MaxSizeAutoCommitTest, DistributedIntervalFacetingTest, TestPerFieldSimilarityWithDefaultOverride, TestInfoStreamLogging, ShardRoutingTest, ByteBuffersDirectoryFactoryTest, AuditLoggerIntegrationTest, TestFoldingMultitermQuery, SampleTest, TestCloudPseudoReturnFields, TestNumericTerms64, TestAtomicUpdateErrorCases, TestDynamicLoading, BitVectorTest, CdcrUpdateLogTest, DocValuesNotIndexedTest, SolrMetricReporterTest, AdminHandlersProxyTest, HLLUtilTest, SolrCmdDistributorTest, ZkDynamicConfigTest, ClassificationUpdateProcessorTest, SpellCheckComponentTest, TestSolrConfigHandler, DistribDocExpirationUpdateProcessorTest, TestAuthorizationFramework, TestDistributedMap, HealthCheckHandlerTest, PreAnalyzedUpdateProcessorTest, LeaderElectionTest, LeaderTragicEventTest]
[junit4] Completed [499/923 (1!)] on J1 in 16.77s, 2 tests, 1 error <<< FAILURES!
[...truncated 52205 lines...]