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/01/25 12:45:35 UTC
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 170 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/170/
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([E35D74724D2D3E92:8EA1D08FF765C195]: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 15595 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 1805653 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-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-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1805654 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/data-dir-156-001
[junit4] 2> 1805654 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 1805655 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1805656 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1805656 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1805657 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/tempDir-001
[junit4] 2> 1805657 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1805657 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1805658 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1805658 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1805757 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 42591
[junit4] 2> 1805758 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:42591
[junit4] 2> 1805758 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42591
[junit4] 2> 1805758 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42591
[junit4] 2> 1805759 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805761 INFO (zkConnectionManagerCallback-18286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805761 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805769 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805772 INFO (zkConnectionManagerCallback-18288-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805772 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805773 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805774 INFO (zkConnectionManagerCallback-18290-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805774 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1805877 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1805878 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1805878 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1805878 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1805878 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3348886f{/solr,null,AVAILABLE}
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@cce5431{SSL, (ssl, http/1.1)}{127.0.0.1:३३६४९}
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.e.j.s.Server Started @१८०५९२१ms
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:42591/solr, hostPort=33649}
[junit4] 2> 1805879 ERROR (jetty-launcher-18291-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1805879 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-25T12:16:15.560Z
[junit4] 2> 1805880 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805881 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1805881 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1805881 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1805881 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d7146f4{/solr,null,AVAILABLE}
[junit4] 2> 1805882 INFO (zkConnectionManagerCallback-18293-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69c259c0{SSL, (ssl, http/1.1)}{127.0.0.1:४५४१७}
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.e.j.s.Server Started @१८०५९२४ms
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:42591/solr, hostPort=45417}
[junit4] 2> 1805882 ERROR (jetty-launcher-18291-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1805882 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-25T12:16:15.563Z
[junit4] 2> 1805883 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1805896 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805898 WARN (jetty-launcher-18291-thread-2-SendThread(127.0.0.1:42591)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f06645210003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f06645210003, 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 0x102f06645210003, 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> 1805898 INFO (zkConnectionManagerCallback-18295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1805898 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805900 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1805910 WARN (jetty-launcher-18291-thread-1-SendThread(127.0.0.1:42591)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f06645210004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f06645210004, 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 0x102f06645210004, 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> 1806297 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1806298 WARN (jetty-launcher-18291-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> 1806360 WARN (jetty-launcher-18291-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19206315[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806360 WARN (jetty-launcher-18291-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19206315[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806510 WARN (jetty-launcher-18291-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> 1806516 WARN (jetty-launcher-18291-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4942f6d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806516 WARN (jetty-launcher-18291-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4942f6d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806517 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42591/solr
[junit4] 2> 1806518 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1806519 INFO (zkConnectionManagerCallback-18309-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806519 INFO (jetty-launcher-18291-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1806566 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1806566 WARN (jetty-launcher-18291-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> 1806567 WARN (jetty-launcher-18291-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@db1cfa8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806567 WARN (jetty-launcher-18291-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@db1cfa8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806568 WARN (jetty-launcher-18291-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> 1806569 WARN (jetty-launcher-18291-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6280f63a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806569 WARN (jetty-launcher-18291-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6280f63a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1806570 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42591/solr
[junit4] 2> 1806573 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1806574 INFO (zkConnectionManagerCallback-18317-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806574 INFO (jetty-launcher-18291-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1806575 WARN (jetty-launcher-18291-thread-2-SendThread(127.0.0.1:42591)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f06645210006.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f06645210006, 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 0x102f06645210006, 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> 1806621 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1806621 INFO (zkConnectionManagerCallback-18319-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806621 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1806692 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45417_solr
[junit4] 2> 1806693 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer Overseer (id=72884866028470279-127.0.0.1:45417_solr-n_0000000000) starting
[junit4] 2> 1806697 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45417_solr
[junit4] 2> 1806697 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45417_solr
[junit4] 2> 1806698 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1806700 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1806700 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1806700 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1806702 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1806702 WARN (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_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> 1806709 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1806715 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:45417_solr]
[junit4] 2> 1806715 DEBUG (ScheduledTrigger-18324-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> 1806717 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1806717 DEBUG (OverseerAutoScalingTriggerThread-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1806721 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1806721 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1806722 INFO (zkConnectionManagerCallback-18332-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806722 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1806728 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1806731 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33649_solr as DOWN
[junit4] 2> 1806732 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1806732 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33649_solr
[junit4] 2> 1806732 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:33649_solr"} current state version: 0
[junit4] 2> 1806732 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:33649_solr
[junit4] 2> 1806733 INFO (zkCallback-18318-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806733 INFO (zkCallback-18331-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1806734 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1806734 WARN (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_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> 1806749 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1806751 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45417.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806759 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45417.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806759 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45417.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806761 INFO (jetty-launcher-18291-thread-1) [n:127.0.0.1:45417_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/tempDir-001/node1
[junit4] 2> 1806784 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33649.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806793 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33649.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806793 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33649.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1806794 INFO (jetty-launcher-18291-thread-2) [n:127.0.0.1:33649_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/tempDir-001/node2
[junit4] 2> 1806805 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1806806 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1806806 INFO (zkConnectionManagerCallback-18346-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1806806 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1806807 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1806808 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42591/solr ready
[junit4] 2> 1806842 INFO (OverseerThreadFactory-18326-thread-1-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1806845 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1806845 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 1806846 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 1806948 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45417_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1806949 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:45417_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1806950 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1806950 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:45417_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807051 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 1807177 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807177 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1807178 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807184 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_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> 1807184 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_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> 1807184 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:45417/solr"}
[junit4] 2> 1807184 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1807190 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1807197 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807198 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807198 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807200 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807202 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807202 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807203 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45417.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e0d5060
[junit4] 2> 1807203 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 1807250 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807250 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807253 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807253 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1807256 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807257 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807257 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807258 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807258 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807258 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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 1689860940170788864
[junit4] 2> 1807261 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807261 INFO (searcherExecutor-18348-thread-1-processing-n:127.0.0.1:45417_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:45417_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> 1807266 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807266 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807269 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807269 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807269 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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:45417/solr/.system_shard1_replica_n1/
[junit4] 2> 1807270 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807270 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:45417/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 1807270 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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/72884866028470279-core_node2-n_0000000000
[junit4] 2> 1807272 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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:45417/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 1807273 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807273 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "node_name":"127.0.0.1:45417_solr",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 1807373 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 1807374 INFO (zkCallback-18318-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> 1807374 INFO (zkCallback-18318-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> 1807374 INFO (zkCallback-18318-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> 1807375 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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> 1807377 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_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> 1807377 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_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> 1807377 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:45417_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true",
[junit4] 2> "base_url":"https://127.0.0.1:45417/solr"}
[junit4] 2> 1807377 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807377 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/2 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:45417_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1807378 INFO (qtp1664758448-33980) [n:127.0.0.1:45417_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=201
[junit4] 2> 1807380 INFO (qtp104218433-33970) [n:127.0.0.1:33649_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> 1807380 INFO (qtp104218433-33970) [n:127.0.0.1:33649_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807478 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 1807478 INFO (zkCallback-18331-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> 1807478 INFO (zkCallback-18318-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> 1807478 INFO (zkCallback-18318-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> 1807479 INFO (zkCallback-18318-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> 1807479 INFO (zkCallback-18318-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1807479 INFO (qtp104218433-33970) [n:127.0.0.1:33649_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:45417_solr&wt=javabin&version=2} status=0 QTime=646
[junit4] 2> 1807479 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 1807479 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807485 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1807486 INFO (OverseerCollectionConfigSetProcessor-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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> 1807486 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 1807487 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 1807488 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 1807489 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 1807492 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807493 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807494 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807495 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807496 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807497 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807499 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807500 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807501 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807502 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807503 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807505 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807509 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807513 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807520 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807522 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807523 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807524 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807525 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807526 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807527 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807528 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807529 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807530 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807531 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807532 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807534 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807535 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807536 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807537 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807538 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807539 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807540 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807541 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807543 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807544 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807545 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807546 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807547 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807548 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 1807549 INFO (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 1807553 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1807553 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1807655 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 1807716 DEBUG (ScheduledTrigger-18324-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1807756 WARN (OverseerThreadFactory-18326-thread-2-processing-n:127.0.0.1:45417_solr) [n:127.0.0.1:45417_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> 1807760 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1807760 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1807761 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1807761 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807763 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1807763 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1807766 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807766 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807768 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1807768 INFO (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1807769 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807769 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1807869 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 1807972 INFO (qtp104218433-33970) [n:127.0.0.1:33649_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> 1807973 INFO (qtp104218433-33970) [n:127.0.0.1:33649_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807974 INFO (qtp104218433-33967) [n:127.0.0.1:33649_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> 1807975 INFO (qtp104218433-33967) [n:127.0.0.1:33649_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807976 INFO (qtp104218433-33969) [n:127.0.0.1:33649_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> 1807977 INFO (qtp104218433-33969) [n:127.0.0.1:33649_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1807978 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33649_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> 1807978 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_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> 1807978 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:33649/solr"}
[junit4] 2> 1807978 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_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:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1807985 INFO (qtp104218433-33970) [n:127.0.0.1:33649_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node3 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1807990 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33649_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> 1807990 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33649_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> 1807990 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:33649/solr"}
[junit4] 2> 1807990 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_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:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:33649_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1807991 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, 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:33649_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> 1807991 DEBUG (OverseerStateUpdate-72884866028470279-127.0.0.1:45417_solr-n_0000000000) [n:127.0.0.1:45417_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:33649_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTes
[...truncated too long message...]
ead-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d7146f4{/solr,null,STOPPED}
[junit4] 2> 1872513 INFO (jetty-closer-18398-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1872514 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1872514 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker-SendThread(127.0.0.1:42591)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f06645210000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f06645210000, 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 0x102f06645210000, 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> 1872616 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker-SendThread(127.0.0.1:42591)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f06645210001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f06645210001, 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 0x102f06645210001, 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> 1872717 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> 19 /solr/collections/.system/state.json
[junit4] 2> 16 /solr/collections/AutoscalingHistoryHandlerTest_collection/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> 7 /solr/collections/.system/state.json
[junit4] 2> 6 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1872720 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:42591
[junit4] 2> 1872720 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42591
[junit4] 2> 1872720 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[E35D74724D2D3E92]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42591
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_E35D74724D2D3E92-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {action_s=Lucene84, event.property.preferredOperation_ss=Lucene84, event.source_s=PostingsFormat(name=Asserting), event.property.replicaType_ss=Lucene84, type=Lucene84, before.actions_ss=PostingsFormat(name=Asserting), event.property._enqueue_time__ss=Lucene84, error.details_t=PostingsFormat(name=Asserting), stage_s=Lucene84, event.id_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), event.property.eventTimes_ss=Lucene84, source_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), event.type_s=Lucene84, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), event.property.nodeNames_ss=Lucene84, error.message_t=Lucene84}, docValues:{_version_=DocValuesFormat(name=Lucene80), event.time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1353, maxMBSortInHeap=7.794401310101233, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=hi-IN, timezone=America/Aruba
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=139948704,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [TestBulkSchemaAPI, TestTrie, JsonLoaderTest, TestStressVersions, CustomTermsComponentTest, TestQueryTypes, TestDocSet, TestSkipOverseerOperations, RecoveryAfterSoftCommitTest, SimpleCollectionCreateDeleteTest, DateMathParserTest, LargeFieldTest, TestCSVResponseWriter, TestExactSharedStatsCache, DataDrivenBlockJoinTest, TestLegacyBM25SimilarityFactory, TestJavabinTupleStreamParser, TestSimScenario, TestRecoveryHdfs, MetricsHandlerTest, HttpPartitionWithTlogReplicasTest, TestUpdate, RankFieldTest, TestSimLargeCluster, TestSSLRandomization, TestConfigSetProperties, TestFreeTextSuggestions, SuggesterFSTTest, RuleEngineTest, TestSafeXMLParsing, TestCorePropertiesReload, CategoryRoutedAliasUpdateProcessorTest, VersionInfoTest, TestConfigSetsAPIExclusivity, HdfsLockFactoryTest, SecurityHeadersTest, DocExpirationUpdateProcessorFactoryTest, TokenizerChainTest, TestSweetSpotSimilarityFactory, TestFieldCacheReopen, TestWaitForStateWithJettyShutdowns, RAMDirectoryFactoryTest, PolyFieldTest, URLClassifyProcessorTest, SolrCloudReportersTest, SolrCoreMetricManagerTest, SegmentsInfoRequestHandlerTest, DistributedQueueTest, SearchHandlerTest, OverseerRolesTest, DirectSolrConnectionTest, TestExceedMaxTermLength, ForceLeaderWithTlogReplicasTest, HdfsRestartWhileUpdatingTest, CursorPagingTest, TestGroupingSearch, SharedFSAutoReplicaFailoverTest, ScheduledTriggerTest, PhrasesIdentificationComponentTest, NumberUtilsTest, TestChildDocTransformerHierarchy, NodeAddedTriggerIntegrationTest, FileBasedSpellCheckerTest, HdfsChaosMonkeySafeLeaderTest, AuditLoggerPluginTest, ZkControllerTest, PeerSyncTest, TestSubQueryTransformerDistrib, ZookeeperStatusHandlerTest, TestContainerPlugin, AnalyticsQueryTest, WrapperMergePolicyFactoryTest, TriggerSetPropertiesIntegrationTest, TestFacetMethods, TestAnalyzeInfixSuggestions, DistributedTermsComponentTest, TestDeleteCollectionOnDownNodes, ExplicitHLLTest, TestGraphTermsQParserPlugin, SpellPossibilityIteratorTest, BlobRepositoryCloudTest, TestFastOutputStream, IndexSizeTriggerSizeEstimationTest, MultiSolrCloudTestCaseTest, TestImpersonationWithHadoopAuth, SearchHandlerTest, TestSolrJ, TestSQLHandler, TestStressThreadBackup, TestSortByMinMaxFunction, ZkSolrClientTest, TestValueSourceCache, RegexBoostProcessorTest, TestRawTransformer, TestFieldCollectionResource, TermsComponentTest, TestMacros, MinimalSchemaTest, CloneFieldUpdateProcessorFactoryTest, TestJmxIntegration, SaslZkACLProviderTest, InfoHandlerTest, SolrGraphiteReporterTest, HDFSCollectionsAPITest, QueryParsingTest, TestDynamicFieldNamesIndexCorrectly, BlockJoinFacetRandomTest, TestHttpServletCarrier, JWTAuthPluginIntegrationTest, DeleteStatusTest, TestRandomFaceting, ManagedSchemaRoundRobinCloudTest, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, AliasIntegrationTest, BasicDistributedZk2Test, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderTest, ChaosMonkeySafeLeaderWithPullReplicasTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, CreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, LeaderFailureAfterFreshStartTest, MetricsHistoryIntegrationTest, MigrateRouteKeyTest, ReindexCollectionTest, TestCloudPseudoReturnFields, TestCloudRecovery, TestLeaderElectionWithEmptyReplica, TestPullReplica, TestPullReplicaErrorHandling, TestStressLiveNodes, TestHdfsCloudBackupRestore, AutoAddReplicasIntegrationTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, TestPolicyCloud, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, TestDynamicURP, TestMergePolicyConfig, TestReloadAndDeleteDocs, TestSolrDeletionPolicy1, TestSolrDeletionPolicy2, TestSolrCoreSnapshots, BinaryUpdateRequestHandlerTest, TestHdfsBackupRestoreCore, TestReplicationHandlerBackup, V2StandaloneTest, XmlUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest]
[junit4] Completed [695/924 (1!)] on J3 in 67.25s, 1 test, 1 failure <<< FAILURES!
[...truncated 51501 lines...]
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 171 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/171/
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([66DBE5A40A8B3A15:B274159B0C3C512]: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 14884 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 1200872 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-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-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1200872 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1200873 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/data-dir-103-001
[junit4] 2> 1200873 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1200877 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1200878 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1200878 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/tempDir-001
[junit4] 2> 1200878 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1200886 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1200886 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1200887 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1200986 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 34797
[junit4] 2> 1200986 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34797
[junit4] 2> 1200986 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34797
[junit4] 2> 1200986 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34797
[junit4] 2> 1200994 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201000 INFO (zkConnectionManagerCallback-10587-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201000 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201040 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201060 INFO (zkConnectionManagerCallback-10589-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201060 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201072 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201076 INFO (zkConnectionManagerCallback-10591-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201076 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201080 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker-SendThread(127.0.0.1:34797)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f572bb140002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f572bb140002, 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 0x102f572bb140002, 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> 1201305 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1201305 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1201305 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1201306 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1201306 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1201306 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 1201331 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1201331 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1201332 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1201333 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@561e3251{/solr,null,AVAILABLE}
[junit4] 2> 1201334 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1201334 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1201334 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1201334 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64369c1{/solr,null,AVAILABLE}
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@414f374a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36661}
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.e.j.s.Server Started @1201360ms
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:34797/solr, hostPort=36661}
[junit4] 2> 1201335 ERROR (jetty-launcher-10592-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1201335 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-26T11:50:22.595Z
[junit4] 2> 1201336 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13177ba2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:44405}
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.e.j.s.Server Started @1201362ms
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:34797/solr, hostPort=44405}
[junit4] 2> 1201337 ERROR (jetty-launcher-10592-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1201337 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-26T11:50:22.597Z
[junit4] 2> 1201348 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201348 INFO (zkConnectionManagerCallback-10594-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201348 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201349 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1201352 INFO (zkConnectionManagerCallback-10596-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201352 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201354 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1201361 WARN (jetty-launcher-10592-thread-1-SendThread(127.0.0.1:34797)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f572bb140004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f572bb140004, 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 0x102f572bb140004, 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> 1201929 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1201930 WARN (jetty-launcher-10592-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19602032[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1201930 WARN (jetty-launcher-10592-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19602032[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1201964 WARN (jetty-launcher-10592-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6cc6d954[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1201964 WARN (jetty-launcher-10592-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6cc6d954[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1201965 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34797/solr
[junit4] 2> 1201968 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1201980 INFO (zkConnectionManagerCallback-10610-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1201980 INFO (jetty-launcher-10592-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1201985 WARN (jetty-launcher-10592-thread-2-SendThread(127.0.0.1:34797)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f572bb140005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f572bb140005, 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 0x102f572bb140005, 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> 1202207 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1202208 INFO (zkConnectionManagerCallback-10612-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1202209 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1202323 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36661_solr
[junit4] 2> 1202323 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer Overseer (id=72890417105076230-127.0.0.1:36661_solr-n_0000000000) starting
[junit4] 2> 1202328 INFO (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36661_solr
[junit4] 2> 1202331 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36661_solr
[junit4] 2> 1202333 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1202334 WARN (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_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> 1202336 INFO (zkCallback-10611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1202344 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1202345 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1202345 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1202345 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1202349 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:36661_solr]
[junit4] 2> 1202356 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1202359 DEBUG (OverseerAutoScalingTriggerThread-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1202359 DEBUG (ScheduledTrigger-10617-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1202364 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1202443 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36661.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1202454 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36661.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1202454 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36661.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1202457 INFO (jetty-launcher-10592-thread-2) [n:127.0.0.1:36661_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/tempDir-001/node2
[junit4] 2> 1203122 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1203126 WARN (jetty-launcher-10592-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@516c3317[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203126 WARN (jetty-launcher-10592-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@516c3317[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203136 WARN (jetty-launcher-10592-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a988346[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203136 WARN (jetty-launcher-10592-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a988346[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1203137 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34797/solr
[junit4] 2> 1203152 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1203164 INFO (zkConnectionManagerCallback-10632-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203164 INFO (jetty-launcher-10592-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1203173 WARN (jetty-launcher-10592-thread-1-SendThread(127.0.0.1:34797)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f572bb140007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f572bb140007, 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 0x102f572bb140007, 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> 1203288 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1203292 INFO (zkConnectionManagerCallback-10634-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203292 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1203309 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1203319 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44405_solr as DOWN
[junit4] 2> 1203321 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1203321 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44405_solr
[junit4] 2> 1203321 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:44405_solr"} current state version: 0
[junit4] 2> 1203322 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44405_solr
[junit4] 2> 1203322 INFO (zkCallback-10611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1203323 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1203324 WARN (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_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> 1203333 INFO (zkCallback-10633-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1203362 DEBUG (ScheduledTrigger-10617-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1203469 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1203519 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44405.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1203668 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44405.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1203668 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44405.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1203669 INFO (jetty-launcher-10592-thread-1) [n:127.0.0.1:44405_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/tempDir-001/node1
[junit4] 2> 1203688 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1203689 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1203691 INFO (zkConnectionManagerCallback-10647-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1203691 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1203692 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1203693 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34797/solr ready
[junit4] 2> 1203737 INFO (OverseerThreadFactory-10619-thread-1-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1203742 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1203742 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 1203742 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 1204028 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:36661_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1204028 INFO (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:36661_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1204029 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1204029 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:36661_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1204130 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 1204235 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204235 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1204238 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204256 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_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> 1204256 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_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> 1204256 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:36661/solr"}
[junit4] 2> 1204256 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1204268 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1204274 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204277 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204277 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204279 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204286 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204287 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204287 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36661.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9a30815
[junit4] 2> 1204287 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/tempDir-001/node2/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001/tempDir-001/node2/.system_shard1_replica_n1/data/]
[junit4] 2> 1204340 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204340 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204341 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204341 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1204344 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204344 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204344 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204346 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204346 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204346 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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 1689949910446637056
[junit4] 2> 1204349 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204350 INFO (searcherExecutor-10649-thread-1-processing-n:127.0.0.1:36661_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:36661_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> 1204357 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 1204358 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204358 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204363 DEBUG (ScheduledTrigger-10617-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1204368 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204368 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204368 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36661/solr/.system_shard1_replica_n1/
[junit4] 2> 1204369 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204370 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:36661/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 1204370 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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/72890417105076230-core_node2-n_0000000000
[junit4] 2> 1204375 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36661/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 1204376 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204376 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "node_name":"127.0.0.1:36661_solr",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 1204608 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 1204613 INFO (zkCallback-10611-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> 1204616 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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> 1204619 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204619 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_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> 1204619 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_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> 1204619 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:36661_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true",
[junit4] 2> "base_url":"http://127.0.0.1:36661/solr"}
[junit4] 2> 1204619 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:36661_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1204621 INFO (qtp1334362315-15725) [n:127.0.0.1:36661_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=386
[junit4] 2> 1204624 INFO (qtp1334362315-15724) [n:127.0.0.1:36661_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> 1204624 INFO (qtp1334362315-15724) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204720 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 1204720 INFO (zkCallback-10611-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> 1204720 INFO (zkCallback-10611-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> 1204721 INFO (qtp1334362315-15724) [n:127.0.0.1:36661_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:36661_solr&wt=javabin&version=2} status=0 QTime=1022
[junit4] 2> 1204722 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 1204722 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1204736 INFO (OverseerCollectionConfigSetProcessor-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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> 1204737 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1204737 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 1204738 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 1204741 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 1204746 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 1204749 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204751 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204756 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204759 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204763 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204769 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204772 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204776 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204778 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204781 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204787 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204791 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204799 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204806 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204969 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204974 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204978 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204982 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204987 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204991 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204997 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1204999 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205002 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205007 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205013 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205015 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205019 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205023 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205026 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205030 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205033 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205036 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205039 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205044 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205047 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205051 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205055 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205058 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205062 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205065 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 1205068 INFO (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 1205079 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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:44405_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1205079 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1205181 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 1205408 DEBUG (ScheduledTrigger-10617-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1205412 WARN (OverseerThreadFactory-10619-thread-2-processing-n:127.0.0.1:36661_solr) [n:127.0.0.1:36661_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> 1205419 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1205419 INFO (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1205422 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1205422 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1205424 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1205424 INFO (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1205427 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1205427 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1205431 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1205431 INFO (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1205434 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1205434 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1205535 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 1205644 INFO (qtp500205160-15720) [n:127.0.0.1:44405_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n3&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1205645 INFO (qtp500205160-15722) [n:127.0.0.1:44405_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&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> 1205647 INFO (qtp500205160-15727) [n:127.0.0.1:44405_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n5 ] 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_n5&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1205649 INFO (qtp500205160-15720) [n:127.0.0.1:44405_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node4 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1205651 INFO (qtp500205160-15727) [n:127.0.0.1:44405_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node6 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n5 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1205652 INFO (qtp500205160-15722) [n:127.0.0.1:44405_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node2 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1205657 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205657 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205657 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:44405/solr"}
[junit4] 2> 1205657 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1205671 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205671 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205671 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node6:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:44405/solr"}
[junit4] 2> 1205671 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_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_node2":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1205675 INFO (qtp500205160-15722) [n:127.0.0.1:44405_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node2 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1205681 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205681 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44405_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> 1205681 DEBUG (OverseerStateUpdate-72890417105076230-127.0.0.1:36661_solr-n_0000000000) [n:127.0.0.1:36661_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:44405_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:44405/solr"}
[junit4]
[...truncated too long message...]
Type":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[
[junit4] 2> 12707941706676019,
[junit4] 2> 12707941706695076],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":12707941706837707,
[junit4] 2> "nodeNames":[
[junit4] 2> "127.0.0.1:44405_solr",
[junit4] 2> "127.0.0.1:33507_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:193) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:331) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> Caused by: java.lang.InterruptedException
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:78) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:351) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.getAutoScalingConfig(ZkDistribStateManager.java:190) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.cloud.DistribStateManager.getAutoScalingConfig(DistribStateManager.java:84) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ComputePlanAction.process(ComputePlanAction.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:331) ~[java/:?]
[junit4] 2> ... 6 more
[junit4] 2> 1271118 DEBUG (AutoscalingActionExecutor-10618-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event id=2d25ce3f40b733T83ge3t8i5yc1ouwmo0z0zyk86
[junit4] 2> 1271118 DEBUG (closeThreadPool-10716-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 1271118 DEBUG (closeThreadPool-10716-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1271118 DEBUG (closeThreadPool-10716-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1271119 INFO (closeThreadPool-10716-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72890417105076230-127.0.0.1:36661_solr-n_0000000000) closing
[junit4] 2> 1271218 INFO (jetty-closer-10699-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72890417105076230-127.0.0.1:36661_solr-n_0000000000) closing
[junit4] 2> 1271219 INFO (jetty-closer-10699-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@414f374a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1271220 INFO (jetty-closer-10699-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@561e3251{/solr,null,STOPPED}
[junit4] 2> 1271220 INFO (jetty-closer-10699-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1271220 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1271323 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker-SendThread(127.0.0.1:34797)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102f572bb140001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102f572bb140001, 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 0x102f572bb140001, 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> 1271424 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/.system/terms/shard1
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 12 /solr/autoscaling.json
[junit4] 2> 11 /solr/collections/.system/state.json
[junit4] 2> 5 /solr/clusterstate.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> 8 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> 1271428 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34797
[junit4] 2> 1271428 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34797
[junit4] 2> 1271428 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[66DBE5A40A8B3A15]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34797
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_66DBE5A40A8B3A15-001
[junit4] 2> Jan 26, 2021 11:51:32 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {action_s=Lucene84, event.property.preferredOperation_ss=PostingsFormat(name=LuceneVarGapFixedInterval), event.source_s=PostingsFormat(name=MockRandom), event.property.replicaType_ss=Lucene84, type=Lucene84, before.actions_ss=PostingsFormat(name=MockRandom), event.property._enqueue_time__ss=Lucene84, error.details_t=PostingsFormat(name=MockRandom), stage_s=Lucene84, event.id_s=PostingsFormat(name=Asserting), event.property.eventTimes_ss=Lucene84, source_s=PostingsFormat(name=Asserting), event.type_s=Lucene84, id=PostingsFormat(name=Asserting), event.property.nodeNames_ss=Lucene84, error.message_t=Lucene84}, docValues:{_version_=DocValuesFormat(name=Lucene80), event.time_l=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1118, maxMBSortInHeap=7.058379232779922, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=hr-HR, timezone=Europe/Moscow
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=140540696,total=518520832
[junit4] 2> NOTE: All tests run in this JVM: [ZkCLITest, DistribJoinFromCollectionTest, SecurityConfHandlerTest, DistributedFacetSimpleRefinementLongTailTest, TestSystemIdResolver, TestJsonFacets, TestDownShardTolerantSearch, TestClusterStateMutator, TestRandomCollapseQParserPlugin, ClassificationUpdateProcessorTest, AggValueSourceTest, SolrCloudExampleTest, DOMUtilTest, IndexSizeTriggerMixedBoundsTest, HdfsTlogReplayBufferedWhileIndexingTest, TestRestManager, OverseerStatusTest, DistributedFacetPivotSmallAdvancedTest, TestPhraseSuggestions, SolrInfoBeanTest, TestRestoreCore, TestCloudInspectUtil, SignatureUpdateProcessorFactoryTest, SolrMetricsIntegrationTest, OverseerModifyCollectionTest, SubstringBytesRefFilterTest, BlockJoinFacetDistribTest, SimpleMLTQParserTest, WordBreakSolrSpellCheckerTest, TestTrieFacet, TestConfigSetImmutable, TestReplicationHandler, CdcrUpdateLogTest, DistributedQueryComponentCustomSortTest, UtilsToolTest, TestConfigReload, DistributedSuggestComponentTest, DateFieldTest, SimplePostToolTest, MoreLikeThisComponentTest, OpenExchangeRatesOrgProviderTest, MaxSizeAutoCommitTest, TestStressUserVersions, TestOnReconnectListenerSupport, TestSolrDeletionPolicy1, ConcurrentCreateRoutedAliasTest, TestHttpShardHandlerFactory, TestFastLRUCache, DirectSolrSpellCheckerTest, DeleteInactiveReplicaTest, TestManagedSynonymFilterFactory, TestSimPolicyCloud, TestFileDictionaryLookup, CdcrOpsAndBoundariesTest, TestInitParams, TestSolr4Spatial, SystemLogListenerTest, ResponseHeaderTest, ReturnFieldsTest, TermVectorComponentTest, CdcrWithNodesRestartsTest, TestCharFilters, CustomHighlightComponentTest, ResponseLogComponentTest, TestExportTool, TestCloudJSONFacetSKG, TestCryptoKeys, TestRecovery, TestEmbeddedSolrServerConstructors, MetricTriggerTest, TestSizeLimitedDistributedMap, HdfsRecoveryZkTest, TestCollectionAPIs, TestAtomicUpdateErrorCases, TestNoOpRegenerator, TestDFISimilarityFactory, LeaderVoteWaitTimeoutTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, NodeMutatorTest, RestartWhileUpdatingTest, TestMinimalConfig, TestCustomSort, TestSubQueryTransformerCrossCore, V2StandaloneTest, UpdateRequestProcessorFactoryTest, TestRTimerTree, TriggerIntegrationTest, PrimitiveFieldTypeTest, SolrSlf4jReporterTest, TestRemoteStreaming, MetricsDisabledCloudTest, AutoscalingHistoryHandlerTest]
[junit4] Completed [475/924 (1!)] on J2 in 71.78s, 1 test, 1 failure <<< FAILURES!
[...truncated 52226 lines...]