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/18 18:41:44 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 1411 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/1411/
1 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testNodeAdded
Error Message:
Action was not fired till 30 seconds
Stack Trace:
java.lang.AssertionError: Action was not fired till 30 seconds
at __randomizedtesting.SeedInfo.seed([6D8EB9FF1779AAC8:84DEF88B5DA02CB]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:471)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15274 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> 1587266 INFO (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1587267 INFO (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1587267 INFO (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/data-dir-113-001
[junit4] 2> 1587268 WARN (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1397 numCloses=1397
[junit4] 2> 1587268 INFO (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1587269 INFO (SUITE-TestWithCollection-seed#[6D8EB9FF1779AAC8]-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> 1587272 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded
[junit4] 2> 1587272 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001
[junit4] 2> 1587273 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1587273 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1587273 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1587274 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1587373 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer start zk server on port: 45937
[junit4] 2> 1587373 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45937
[junit4] 2> 1587373 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45937
[junit4] 2> 1587373 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45937
[junit4] 2> 1587375 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1587378 INFO (zkConnectionManagerCallback-12314-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1587378 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1587381 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1587382 INFO (zkConnectionManagerCallback-12316-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1587382 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1587383 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1587384 INFO (zkConnectionManagerCallback-12318-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1587384 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1587488 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1587488 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1587488 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1587488 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1587489 INFO (jetty-launcher-12319-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> 1587489 INFO (jetty-launcher-12319-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> 1587497 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1587497 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1587498 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1587501 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1587501 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1587501 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1587501 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@65219dc4{/solr,null,AVAILABLE}
[junit4] 2> 1587501 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@71463126{/solr,null,AVAILABLE}
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13be7021{SSL, (ssl, http/1.1)}{127.0.0.1:41551}
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.e.j.s.Server Started @1587533ms
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:45937/solr, hostPort=41551}
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21f080ac{SSL, (ssl, http/1.1)}{127.0.0.1:38405}
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.e.j.s.Server Started @1587533ms
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:45937/solr, hostPort=38405}
[junit4] 2> 1587502 ERROR (jetty-launcher-12319-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1587502 ERROR (jetty-launcher-12319-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T18:09:33.301Z
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1587502 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T18:09:33.301Z
[junit4] 2> 1587503 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1587503 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1587504 INFO (zkConnectionManagerCallback-12323-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1587504 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1587504 INFO (zkConnectionManagerCallback-12321-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1587504 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1587505 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1587505 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1587509 WARN (jetty-launcher-12319-thread-1-SendThread(127.0.0.1:45937)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102cd9b01d20003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102cd9b01d20003, 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 0x102cd9b01d20003, 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> 1588071 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1588071 WARN (jetty-launcher-12319-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> 1588072 WARN (jetty-launcher-12319-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50322520[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588072 WARN (jetty-launcher-12319-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50322520[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588075 WARN (jetty-launcher-12319-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> 1588075 WARN (jetty-launcher-12319-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39edc7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588075 WARN (jetty-launcher-12319-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39edc7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588076 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45937/solr
[junit4] 2> 1588077 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1588081 INFO (zkConnectionManagerCallback-12337-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1588081 INFO (jetty-launcher-12319-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1588183 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1588184 INFO (zkConnectionManagerCallback-12339-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1588184 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1588251 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38405_solr
[junit4] 2> 1588252 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.Overseer Overseer (id=72846609625513990-127.0.0.1:38405_solr-n_0000000000) starting
[junit4] 2> 1588257 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38405_solr
[junit4] 2> 1588257 INFO (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38405_solr
[junit4] 2> 1588258 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1588258 INFO (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1588259 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1588259 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1588259 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1588259 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1588260 WARN (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_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> 1588270 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:38405_solr]
[junit4] 2> 1588273 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1588274 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1588274 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1588274 DEBUG (ScheduledTrigger-12344-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> 1588274 TRACE (ScheduledTrigger-12344-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:38405_solr]
[junit4] 2> 1588274 TRACE (ScheduledTrigger-12344-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:38405_solr]
[junit4] 2> 1588305 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38405.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1588324 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38405.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1588324 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38405.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1588325 INFO (jetty-launcher-12319-thread-1) [n:127.0.0.1:38405_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node1
[junit4] 2> 1588559 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1588559 WARN (jetty-launcher-12319-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> 1588561 WARN (jetty-launcher-12319-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4bcb0cec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588561 WARN (jetty-launcher-12319-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4bcb0cec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588563 WARN (jetty-launcher-12319-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> 1588563 WARN (jetty-launcher-12319-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e764d67[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588563 WARN (jetty-launcher-12319-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e764d67[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1588564 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45937/solr
[junit4] 2> 1588565 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1588566 INFO (zkConnectionManagerCallback-12359-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1588566 INFO (jetty-launcher-12319-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1588668 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1588670 INFO (zkConnectionManagerCallback-12361-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1588670 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1588683 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1588687 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41551_solr as DOWN
[junit4] 2> 1589169 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1589169 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41551_solr
[junit4] 2> 1589171 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:41551_solr
[junit4] 2> 1589172 INFO (zkCallback-12338-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1589172 INFO (zkCallback-12360-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1589173 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1589173 WARN (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_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> 1589186 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1589209 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41551.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1589217 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41551.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1589217 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41551.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1589218 INFO (jetty-launcher-12319-thread-2) [n:127.0.0.1:41551_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node2
[junit4] 2> 1589242 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1589243 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1589244 INFO (zkConnectionManagerCallback-12374-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1589244 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1589246 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1589247 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45937/solr ready
[junit4] 2> 1589260 DEBUG (zkCallback-12338-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1589260 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1589264 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1589264 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1589312 INFO (qtp722004956-20545) [n:127.0.0.1:38405_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1589313 INFO (qtp722004956-20545) [n:127.0.0.1:38405_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1589317 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1589318 DEBUG (qtp424024913-20541) [n:127.0.0.1:41551_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1589319 INFO (qtp424024913-20541) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=29
[junit4] 2> 1589319 INFO (TEST-TestWithCollection.testNodeAdded-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.TestWithCollection Chosen node 127.0.0.1:41551_solr for collection testNodeAdded_abc
[junit4] 2> 1589320 DEBUG (zkCallback-12338-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1589320 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1589320 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1589320 DEBUG (OverseerAutoScalingTriggerThread-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1589324 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testNodeAdded_abc
[junit4] 2> 1589324 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testNodeAdded_abc.AUTOCREATED
[junit4] 2> 1589326 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testNodeAdded_abc.AUTOCREATED/protwords.txt
[junit4] 2> 1589328 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testNodeAdded_abc.AUTOCREATED/managed-schema
[junit4] 2> 1589329 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testNodeAdded_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 1589332 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1589334 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1589335 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1589337 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1589339 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1589340 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1589343 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1589344 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1589346 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1589347 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1589349 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1589350 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1589352 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1589353 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1589355 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1589357 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1589358 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1589359 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1589361 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1589363 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1589365 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1589382 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1589385 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1589390 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1589393 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1589396 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1589398 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1589403 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1589405 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1589406 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1589407 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1589409 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1589410 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1589411 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1589413 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1589414 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1589415 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1589417 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1589418 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testNodeAdded_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1589419 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testNodeAdded_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 1589420 INFO (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testNodeAdded_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 1589423 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded_abc
[junit4] 2> 1589424 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testNodeAdded_abc/state.json
[junit4] 2> 1589525 DEBUG (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1589529 INFO (qtp722004956-20546) [n:127.0.0.1:38405_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1589531 INFO (qtp424024913-20536) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1589532 DEBUG (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper New session created, 12039424674692471
[junit4] 2> 1589533 DEBUG (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession 12039424674692471
[junit4] 2> 1589537 INFO (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1589538 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1589538 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_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":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1589638 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_abc/state.json version: 0
[junit4] 2> 1589743 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testNodeAdded_abc.AUTOCREATED&newCollection=true&name=testNodeAdded_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1589744 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1589748 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1589748 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:41551/solr"}
[junit4] 2> 1589748 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded_abc//collections/testNodeAdded_abc/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":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1589756 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1589776 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1589838 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1589851 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_abc/state.json version: 1
[junit4] 2> 1589886 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_abc_shard1_replica_n1' using configuration from configset testNodeAdded_abc.AUTOCREATED, trusted=true
[junit4] 2> 1589886 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41551.solr.core.testNodeAdded_abc.shard1.replica_n1' (registry 'solr.core.testNodeAdded_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1589886 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testNodeAdded_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node2/testNodeAdded_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node2/testNodeAdded_abc_shard1_replica_n1/data/]
[junit4] 2> 1589948 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1589948 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1589949 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1589949 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1589952 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testNodeAdded_abc.AUTOCREATED
[junit4] 2> 1589952 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testNodeAdded_abc.AUTOCREATED
[junit4] 2> 1589952 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testNodeAdded_abc.AUTOCREATED/managed-schema
[junit4] 2> 1589953 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1589953 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1589954 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1589955 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1589955 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689248989974626304
[junit4] 2> 1589957 INFO (searcherExecutor-12376-thread-1-processing-n:127.0.0.1:41551_solr x:testNodeAdded_abc_shard1_replica_n1 c:testNodeAdded_abc s:shard1 r:core_node2) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1589957 INFO (searcherExecutor-12376-thread-1-processing-n:127.0.0.1:41551_solr x:testNodeAdded_abc_shard1_replica_n1 c:testNodeAdded_abc s:shard1 r:core_node2) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1589957 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1589958 INFO (searcherExecutor-12376-thread-1-processing-n:127.0.0.1:41551_solr x:testNodeAdded_abc_shard1_replica_n1 c:testNodeAdded_abc s:shard1 r:core_node2) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testNodeAdded_abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1589963 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testNodeAdded_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1589963 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testNodeAdded_abc/leaders/shard1
[junit4] 2> 1589979 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1589979 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1589979 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:41551/solr/testNodeAdded_abc_shard1_replica_n1/
[junit4] 2> 1589980 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1589980 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:41551/solr/testNodeAdded_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 1589980 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testNodeAdded_abc/leaders/shard1/leader after winning as /collections/testNodeAdded_abc/leader_elect/shard1/election/72846609625513992-core_node2-n_0000000000
[junit4] 2> 1589983 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:41551/solr/testNodeAdded_abc_shard1_replica_n1/ shard1
[junit4] 2> 1589983 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1590084 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_abc/state.json version: 2
[junit4] 2> 1590085 INFO (zkCallback-12360-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590085 INFO (zkCallback-12360-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590086 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1590088 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1590088 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_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:41551/solr"}
[junit4] 2> 1590088 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded_abc//collections/testNodeAdded_abc/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":"testNodeAdded_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1590088 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr c:testNodeAdded_abc s:shard1 r:core_node2 x:testNodeAdded_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1590090 INFO (qtp424024913-20537) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testNodeAdded_abc.AUTOCREATED&newCollection=true&name=testNodeAdded_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=347
[junit4] 2> 1590090 DEBUG (OverseerThreadFactory-12346-thread-1-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper final release, session 12039424674692471 lived a total of 559ms,
[junit4] 2> 1590092 INFO (qtp722004956-20542) [n:127.0.0.1:38405_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> 1590092 INFO (qtp722004956-20542) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1590189 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_abc/state.json version: 3
[junit4] 2> 1590189 INFO (zkCallback-12360-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590189 INFO (zkCallback-12338-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590189 INFO (zkCallback-12360-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590189 INFO (zkCallback-12360-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testNodeAdded_abc/state.json] for collection [testNodeAdded_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1590190 INFO (qtp722004956-20542) [n:127.0.0.1:38405_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testNodeAdded_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:41551_solr&wt=javabin&version=2} status=0 QTime=868
[junit4] 2> 1590193 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testNodeAdded_xyz
[junit4] 2> 1590193 INFO (OverseerCollectionConfigSetProcessor-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_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> 1590194 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testNodeAdded_xyz.AUTOCREATED
[junit4] 2> 1590196 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testNodeAdded_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 1590197 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testNodeAdded_xyz.AUTOCREATED/managed-schema
[junit4] 2> 1590199 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testNodeAdded_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 1590201 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1590202 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1590204 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1590206 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1590207 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1590209 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1590210 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1590217 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1590219 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1590220 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1590222 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1590223 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1590225 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1590226 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1590233 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1590236 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1590238 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1590239 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1590241 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1590242 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1590243 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1590244 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1590246 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1590248 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1590249 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1590250 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1590252 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1590253 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1590254 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1590256 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1590257 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1590258 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1590259 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1590261 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1590262 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1590263 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1590264 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1590265 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1590267 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testNodeAdded_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1590268 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testNodeAdded_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 1590269 INFO (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testNodeAdded_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 1590273 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded_xyz
[junit4] 2> 1590373 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testNodeAdded_xyz/state.json
[junit4] 2> 1590475 DEBUG (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1590480 INFO (qtp722004956-20543) [n:127.0.0.1:38405_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1590481 INFO (qtp424024913-20541) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testNodeAdded_abc.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1590482 INFO (qtp424024913-20539) [n:127.0.0.1:41551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1590483 DEBUG (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper New session created, 12039425626054663
[junit4] 2> 1590486 DEBUG (OverseerThreadFactory-12346-thread-2-processing-n:127.0.0.1:38405_solr) [n:127.0.0.1:38405_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession 12039425626054663
[junit4] 2> 1590490 INFO (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded_xyz",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_xyz_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1590491 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1590491 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_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":"testNodeAdded_xyz_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1590591 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_xyz/state.json version: 0
[junit4] 2> 1590693 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testNodeAdded_xyz.AUTOCREATED&newCollection=true&name=testNodeAdded_xyz_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded_xyz&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1590694 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1590697 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_xyz_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded_xyz",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1590697 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testNodeAdded_xyz_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:41551/solr"}
[junit4] 2> 1590697 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded_xyz//collections/testNodeAdded_xyz/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "withCollection":"testNodeAdded_abc",
[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":"testNodeAdded_xyz_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41551_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1590703 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1590724 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1590769 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1590798 DEBUG (OverseerStateUpdate-72846609625513990-127.0.0.1:38405_solr-n_0000000000) [n:127.0.0.1:38405_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded_xyz/state.json version: 1
[junit4] 2> 1590818 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_xyz_shard1_replica_n1' using configuration from configset testNodeAdded_xyz.AUTOCREATED, trusted=true
[junit4] 2> 1590818 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41551.solr.core.testNodeAdded_xyz.shard1.replica_n1' (registry 'solr.core.testNodeAdded_xyz.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7cff8dbb
[junit4] 2> 1590819 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.c.SolrCore [[testNodeAdded_xyz_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node2/testNodeAdded_xyz_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001/tempDir-001/node2/testNodeAdded_xyz_shard1_replica_n1/data/]
[junit4] 2> 1590873 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1590873 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1590874 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1590874 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1590877 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testNodeAdded_xyz.AUTOCREATED
[junit4] 2> 1590878 INFO (qtp424024913-20544) [n:127.0.0.1:41551_solr c:testNodeAdded_xyz s:shard1 r:core_node2 x:testNodeAdded_xyz_shard1_repl
[...truncated too long message...]
re@290dd3cc
[junit4] 2> 1676063 INFO (recoveryExecutor-13207-thread-1-processing-n:127.0.0.1:44883_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:44883_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3 tag=SolrCore@290dd3cc
[junit4] 2> 1676063 INFO (recoveryExecutor-13207-thread-1-processing-n:127.0.0.1:44883_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:44883_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61c81a98: rootName = solr_44883, domain = solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3, service url = null, agent id = null] for registry solr.core.testAddReplicaWithPolicy_xyz.shard1.replica_n3/com.codahale.metrics.MetricRegistry@34e2bbf8
[junit4] 2> 1676091 INFO (recoveryExecutor-13207-thread-1-processing-n:127.0.0.1:44883_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:44883_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAddReplicaWithPolicy_xyz.shard1.leader tag=SolrCore@290dd3cc
[junit4] 2> 1676092 WARN (recoveryExecutor-13207-thread-1-processing-n:127.0.0.1:44883_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:44883_solr ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[testAddReplicaWithPolicy_xyz_shard1_replica_n3] coreNodeName=[core_node4]
[junit4] 2> 1676092 INFO (recoveryExecutor-13207-thread-1-processing-n:127.0.0.1:44883_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n3 c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node4) [n:127.0.0.1:44883_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1676095 INFO (jetty-closer-13273-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@59812066{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1676095 INFO (jetty-closer-13273-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@579f97f0{/solr,null,STOPPED}
[junit4] 2> 1676095 INFO (jetty-closer-13273-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1676096 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1676198 WARN (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]-SendThread(127.0.0.1:34705)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102cd9c47900001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102cd9c47900001, 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 0x102cd9c47900001, 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> 1676300 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/collections/testAddReplicaWithPolicy_xyz/terms/shard1
[junit4] 2> 5 /solr/collections/testAddReplicaWithPolicy_abc/terms/shard1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/collections/testAddReplicaWithPolicy_abc/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 33 /solr/collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2> 26 /solr/collections/testAddReplicaWithPolicy_xyz/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 40 /solr/overseer/queue
[junit4] 2> 10 /solr/overseer/collection-queue-work
[junit4] 2> 9 /solr/collections/testAddReplicaWithPolicy_xyz/state.json
[junit4] 2> 9 /solr/collections
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 7 /solr/collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2>
[junit4] 2> 1676309 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34705
[junit4] 2> 1676309 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34705
[junit4] 2> 1676309 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34705
[junit4] 2> 1676310 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6D8EB9FF1779AAC8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAddReplicaWithPolicy
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestWithCollection_6D8EB9FF1779AAC8-001
[junit4] 2> Jan 18, 2021 6:11:02 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=1908, maxMBSortInHeap=5.738432171395553, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ms, timezone=America/Cordoba
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=249055064,total=520617984
[junit4] 2> NOTE: All tests run in this JVM: [TestQueryTypes, ComputePlanActionTest, TestHttpServletCarrier, DistributedFacetPivotSmallAdvancedTest, TestFastOutputStream, TestSearcherReuse, SecurityConfHandlerTest, FuzzySearchTest, HttpPartitionTest, TestSafeXMLParsing, HdfsLockFactoryTest, TestMinimalConfig, TestLFUCache, TestOnReconnectListenerSupport, MBeansHandlerTest, TestSolrConfigHandlerConcurrent, ShardSplitTest, ZookeeperReadAPITest, TestNumericTokenStream, ConvertedLegacyTest, TestPostingsSolrHighlighter, BooleanFieldTest, TestFieldTypeResource, CloneFieldUpdateProcessorFactoryTest, SolrCloudExampleTest, FileBasedSpellCheckerTest, LeaderElectionTest, TestRandomDVFaceting, JavabinLoaderTest, TestSizeLimitedDistributedMap, SortByFunctionTest, TestSchemaVersionResource, IndexSizeEstimatorTest, TestDynamicFieldResource, SpellPossibilityIteratorTest, ReturnFieldsTest, DeleteShardTest, TestDownShardTolerantSearch, TestDynamicLoadingUrl, DocExpirationUpdateProcessorFactoryTest, TestBinaryResponseWriter, TestMacroExpander, TestRequestForwarding, MultiDestinationAuditLoggerTest, ExplicitHLLTest, ConcurrentCreateCollectionTest, TestCollectionAPIs, DirectSolrConnectionTest, MetricTriggerTest, MoreLikeThisComponentTest, TestCollectionsAPIViaSolrCloudCluster, HdfsRecoveryZkTest, NumberUtilsTest, MigrateRouteKeyTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestCryptoKeys, TestQueryingOnDownCollection, TestWordDelimiterFilterFactory, TestComplexPhraseLeadingWildcard, ResponseLogComponentTest, XMLAtomicUpdateMultivalueTest, UninvertDocValuesMergePolicyTest, CategoryRoutedAliasUpdateProcessorTest, OpenExchangeRatesOrgProviderTest, TestConfigReload, SolrSlf4jReporterTest, DateMathParserTest, SplitHandlerTest, TestTextField, TestInitParams, TestSolr4Spatial2, AutoscalingHistoryHandlerTest, ScheduledTriggerIntegrationTest, TestFieldSortValues, PeerSyncReplicationTest, DistributedSuggestComponentTest, TestRequestStatusCollectionAPI, CustomHighlightComponentTest, IndexSchemaTest, TestExactSharedStatsCache, TestStressVersions, DateFieldTest, TestSweetSpotSimilarityFactory, SecurityHeadersTest, SystemLogListenerTest, TestReloadAndDeleteDocs, TestXIncludeConfig, RemoteQueryErrorTest, TestCursorMarkWithoutUniqueKey, CoreAdminRequestStatusTest, DistributedExpandComponentTest, CdcrWithNodesRestartsTest, ZkCLITest, IndexSizeTriggerMixedBoundsTest, TestCloudJSONFacetSKGEquiv, BJQFilterAccessibleTest, AnalyticsMergeStrategyTest, DirectoryFactoryTest, ActionThrottleTest, DeleteInactiveReplicaTest, SuggesterFSTTest, TestMinHashQParser, SpatialFilterTest, QueryResultKeyTest, TestSchemaField, QueryEqualityTest, TestCollationField, AuditLoggerPluginTest, TaggerTest, TestValueSourceCache, TestRangeQuery, SolrLogPostToolTest, TestSimGenericDistributedQueue, TestCloudInspectUtil, TestCloudPseudoReturnFields, TestCloudRecovery, TestDeleteCollectionOnDownNodes, TestHashPartitioner, TestPrepRecovery, TestTolerantUpdateProcessorCloud, TestTolerantUpdateProcessorRandomCloud, TestWithCollection]
[junit4] Completed [585/923 (1!)] on J2 in 89.63s, 9 tests, 1 failure <<< FAILURES!
[...truncated 51845 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 1412 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/1412/
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([D891DE97CCD73041:B56D7A6A769FCF46]: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 13586 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 132232 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 132233 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/data-dir-18-001
[junit4] 2> 132233 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 132233 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 132235 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-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> 132235 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 132235 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 132235 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/tempDir-001
[junit4] 2> 132236 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 132236 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 132236 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 132237 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 132336 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 41291
[junit4] 2> 132336 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41291
[junit4] 2> 132336 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41291
[junit4] 2> 132336 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41291
[junit4] 2> 132339 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 132341 INFO (zkConnectionManagerCallback-1041-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 132341 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 132345 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 132346 INFO (zkConnectionManagerCallback-1043-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 132346 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 132347 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 132347 INFO (zkConnectionManagerCallback-1045-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 132348 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 132349 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker-SendThread(127.0.0.1:41291)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce73287d0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce73287d0002, 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 0x102ce73287d0002, 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> 132457 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 132457 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 132457 INFO (jetty-launcher-1046-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> 132523 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 132523 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 132523 INFO (jetty-launcher-1046-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> 132576 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 132576 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 132576 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 132585 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 132585 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 132585 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 132586 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a701d2a{/solr,null,AVAILABLE}
[junit4] 2> 132588 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1851f6f9{/solr,null,AVAILABLE}
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5e0cebf3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:38791}
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.e.j.s.Server Started @132617ms
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:41291/solr, hostPort=38791}
[junit4] 2> 132589 ERROR (jetty-launcher-1046-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 132589 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T22:05:39.101Z
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4c9a0089{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36691}
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.e.j.s.Server Started @132622ms
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:41291/solr, hostPort=36691}
[junit4] 2> 132593 ERROR (jetty-launcher-1046-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 132593 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T22:05:39.105Z
[junit4] 2> 132595 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 132596 INFO (zkConnectionManagerCallback-1048-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 132596 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 132597 INFO (zkConnectionManagerCallback-1050-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 132597 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 132597 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 132601 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 132615 WARN (jetty-launcher-1046-thread-1-SendThread(127.0.0.1:41291)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce73287d0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce73287d0003, 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 0x102ce73287d0003, 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> 132717 WARN (jetty-launcher-1046-thread-2-SendThread(127.0.0.1:41291)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce73287d0004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce73287d0004, 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 0x102ce73287d0004, 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> 133072 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 133073 WARN (jetty-launcher-1046-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7730867f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133073 WARN (jetty-launcher-1046-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7730867f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133076 WARN (jetty-launcher-1046-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b621338[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133076 WARN (jetty-launcher-1046-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b621338[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133077 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41291/solr
[junit4] 2> 133078 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 133080 INFO (zkConnectionManagerCallback-1064-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 133080 INFO (jetty-launcher-1046-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 133182 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 133188 INFO (zkConnectionManagerCallback-1066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 133188 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 133191 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 133192 WARN (jetty-launcher-1046-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@13b204ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133192 WARN (jetty-launcher-1046-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@13b204ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133195 WARN (jetty-launcher-1046-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42a8f3b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133195 WARN (jetty-launcher-1046-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42a8f3b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 133196 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41291/solr
[junit4] 2> 133204 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 133213 INFO (zkConnectionManagerCallback-1076-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 133213 INFO (jetty-launcher-1046-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 133439 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 133446 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38791_solr
[junit4] 2> 133447 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer Overseer (id=72847537987190790-127.0.0.1:38791_solr-n_0000000000) starting
[junit4] 2> 133449 INFO (zkConnectionManagerCallback-1078-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 133449 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 133453 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38791_solr
[junit4] 2> 133459 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36691_solr
[junit4] 2> 133460 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38791_solr as DOWN
[junit4] 2> 133461 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 133461 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38791_solr
[junit4] 2> 133461 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 133462 WARN (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_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> 133465 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 133466 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 133466 WARN (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_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> 133468 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 133469 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 133469 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 133471 INFO (zkCallback-1065-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 133481 INFO (zkCallback-1077-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 133483 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:38791_solr"} current state version: 0
[junit4] 2> 133483 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38791_solr
[junit4] 2> 133483 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:38791_solr, 127.0.0.1:36691_solr]
[junit4] 2> 133484 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 133492 DEBUG (ScheduledTrigger-1083-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> 133500 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 133500 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 133500 DEBUG (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 133525 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36691.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133526 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38791.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133535 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36691.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133535 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36691.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133535 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38791.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133536 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38791.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133536 INFO (jetty-launcher-1046-thread-2) [n:127.0.0.1:36691_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/tempDir-001/node2
[junit4] 2> 133537 INFO (jetty-launcher-1046-thread-1) [n:127.0.0.1:38791_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/tempDir-001/node1
[junit4] 2> 133560 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 133564 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 133565 INFO (zkConnectionManagerCallback-1101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 133565 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 133566 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 133567 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41291/solr ready
[junit4] 2> 133579 INFO (OverseerThreadFactory-1085-thread-1-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 133582 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:38791_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 133582 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 133582 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 133703 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 133703 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 133704 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 133704 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 133804 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 133907 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133909 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133912 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_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> 133912 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_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> 133912 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:38791/solr"}
[junit4] 2> 133912 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 133918 WARN (qtp1355489178-1737) [n:127.0.0.1:38791_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 133918 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133923 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133925 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133925 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133927 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133929 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133929 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 133929 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38791.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@442003b5
[junit4] 2> 133930 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 134010 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134010 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134012 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134012 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 134013 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 134015 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134015 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134015 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134016 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134016 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134016 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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 1689263842816688128
[junit4] 2> 134018 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134025 INFO (searcherExecutor-1103-thread-1-processing-n:127.0.0.1:38791_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:38791_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> 134025 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134025 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134031 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134032 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134032 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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:38791/solr/.system_shard1_replica_n1/
[junit4] 2> 134032 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134033 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:38791/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 134033 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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/72847537987190790-core_node2-n_0000000000
[junit4] 2> 134036 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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:38791/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 134036 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 134036 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134137 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 134138 INFO (zkCallback-1065-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> 134138 INFO (zkCallback-1065-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> 134139 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134141 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_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> 134141 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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> 134141 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_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> 134141 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_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:38791/solr"}
[junit4] 2> 134141 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:38791_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 134142 INFO (qtp1355489178-1737) [n:127.0.0.1:38791_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=236
[junit4] 2> 134144 INFO (qtp1113454258-1731) [n:127.0.0.1:36691_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> 134144 INFO (qtp1113454258-1731) [n:127.0.0.1:36691_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 134241 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 134243 INFO (zkCallback-1065-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> 134243 INFO (zkCallback-1065-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> 134243 INFO (zkCallback-1077-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> 134243 INFO (zkCallback-1065-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> 134244 INFO (qtp1113454258-1731) [n:127.0.0.1:36691_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:38791_solr&wt=javabin&version=2} status=0 QTime=671
[junit4] 2> 134245 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 134246 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 134366 INFO (OverseerCollectionConfigSetProcessor-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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> 134368 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 134368 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 134371 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 134373 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 134375 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 134377 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134378 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134379 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134381 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134382 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134384 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134386 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134389 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134392 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134394 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134395 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134397 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134398 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134399 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134401 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134402 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134403 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134405 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134406 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134407 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134408 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134411 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134413 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134415 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134416 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134417 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134419 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134420 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134421 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134423 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134425 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134426 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134427 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134431 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134437 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134440 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134443 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134444 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134445 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134447 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 134448 INFO (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 134453 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 134453 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 134496 DEBUG (ScheduledTrigger-1083-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> 134554 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 134654 WARN (OverseerThreadFactory-1085-thread-2-processing-n:127.0.0.1:38791_solr) [n:127.0.0.1:38791_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> 134659 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 134659 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 134661 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 134661 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 134664 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 134664 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 134666 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 134666 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 134669 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 134669 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 134673 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 134673 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 134773 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 134873 INFO (qtp1113454258-1734) [n:127.0.0.1:36691_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> 134873 INFO (qtp1113454258-1734) [n:127.0.0.1:36691_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 134873 INFO (qtp1113454258-1731) [n:127.0.0.1:36691_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3 ] 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_n3&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 134874 INFO (qtp1113454258-1730) [n:127.0.0.1:36691_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> 134876 INFO (qtp1113454258-1734) [n:127.0.0.1:36691_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> 134878 INFO (qtp1113454258-1730) [n:127.0.0.1:36691_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> 134879 INFO (qtp1113454258-1731) [n:127.0.0.1:36691_solr c:AutoscalingHistoryHandlerTest_collection s:shard1 r:core_node5 x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n3 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 134880 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_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> 134880 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_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> 134880 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:36691/solr"}
[junit4] 2> 134880 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_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_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 134888 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36691_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> 134888 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36691_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> 134888 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:36691/solr"}
[junit4] 2> 134888 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_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_n3",
[junit4] 2> "node_name":"127.0.0.1:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 134896 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36691_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> 134896 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_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> 134896 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"http://127.0.0.1:36691/solr"}
[junit4] 2> 134896 DEBUG (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_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:36691_solr",
[junit4] 2> "state":"down",
[...truncated too long message...]
ost_trigger after 5000ms
[junit4] 2> 199454 DEBUG (AutoscalingActionExecutor-1084-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 5000ms
[junit4] 2> 199454 DEBUG (AutoscalingActionExecutor-1084-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 57 ms for event id=2ad2bc4846aa5dT2irl4im11l282tthlhxae7zq8
[junit4] 2> 199457 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 199457 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@51630bea: rootName = solr_38791, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@cb93a61
[junit4] 2> 199462 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 199463 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@171d8749: rootName = solr_38791, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@62ebd8c0
[junit4] 2> 199464 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 199464 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20e628fa: rootName = solr_38791, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@575f738d
[junit4] 2> 199465 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 199484 INFO (closeThreadPool-1170-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72847537987190790-127.0.0.1:38791_solr-n_0000000000) closing
[junit4] 2> 199494 INFO (OverseerStateUpdate-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [n:127.0.0.1:38791_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38791_solr
[junit4] 2> 199496 INFO (OverseerAutoScalingTriggerThread-72847537987190790-127.0.0.1:38791_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 199497 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 199497 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 199497 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 199499 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 199499 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 199499 DEBUG (closeThreadPool-1170-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 199499 INFO (closeThreadPool-1170-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72847537987190790-127.0.0.1:38791_solr-n_0000000000) closing
[junit4] 2> 199499 WARN (jetty-launcher-1046-thread-1-SendThread(127.0.0.1:41291)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce73287d0006.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce73287d0006, 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 0x102ce73287d0006, 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> 199558 INFO (jetty-closer-1153-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4c9a0089{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 199559 INFO (jetty-closer-1153-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1851f6f9{/solr,null,STOPPED}
[junit4] 2> 199559 INFO (jetty-closer-1153-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 199599 INFO (jetty-closer-1153-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72847537987190790-127.0.0.1:38791_solr-n_0000000000) closing
[junit4] 2> 199600 INFO (jetty-closer-1153-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5e0cebf3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 199601 INFO (jetty-closer-1153-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5a701d2a{/solr,null,STOPPED}
[junit4] 2> 199601 INFO (jetty-closer-1153-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 199602 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 199603 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker-SendThread(127.0.0.1:41291)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce73287d0000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce73287d0000, 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 0x102ce73287d0000, 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> 199806 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> 20 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 16 /solr/collections/.system/state.json
[junit4] 2> 12 /solr/autoscaling.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 35 /solr/overseer/queue
[junit4] 2> 16 /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> 7 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> 199810 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41291
[junit4] 2> 199810 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41291
[junit4] 2> 199810 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[D891DE97CCD73041]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41291
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_D891DE97CCD73041-001
[junit4] 2> Jan 18, 2021 10:06:46 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene87, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=da-DK, timezone=Asia/Jayapura
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=284944184,total=519569408
[junit4] 2> NOTE: All tests run in this JVM: [TestConfigSetImmutable, CoreAdminRequestStatusTest, TestNumericTokenStream, SolrMetricsIntegrationTest, TestSchemaVersionResource, ForceLeaderTest, AnalyticsMergeStrategyTest, OverseerModifyCollectionTest, TestManagedSchemaAPI, RegexBoostProcessorTest, BlockCacheTest, DirectSolrConnectionTest, MoveReplicaTest, CloneFieldUpdateProcessorFactoryTest, TestCursorMarkWithoutUniqueKey, ConvertedLegacyTest, TestRestManager, TestRandomDVFaceting, TestBM25SimilarityFactory, AutoscalingHistoryHandlerTest]
[junit4] Completed [108/923 (1!)] on J2 in 67.96s, 1 test, 1 failure <<< FAILURES!
[...truncated 53526 lines...]