You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/18 22:15:00 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-14) - Build # 8849
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8849/
Java: 64bit/jdk-14 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([D965585A25D9D470:4A7E10287B248F44]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 15035 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1545272 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[D965585A25D9D470]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1545272 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[D965585A25D9D470]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\data-dir-108-001
[junit4] 2> 1545274 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[D965585A25D9D470]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1545274 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[D965585A25D9D470]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1545276 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[D965585A25D9D470]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1545282 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1545285 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001
[junit4] 2> 1545285 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1545289 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1545290 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1545368 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer start zk server on port: 49326
[junit4] 2> 1545368 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49326
[junit4] 2> 1545368 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49326
[junit4] 2> 1545368 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49326
[junit4] 2> 1545372 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545383 INFO (zkConnectionManagerCallback-13167-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545383 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545401 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545408 INFO (zkConnectionManagerCallback-13169-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545408 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545409 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545414 INFO (zkConnectionManagerCallback-13171-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545415 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1545504 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28cf4f45{/solr,null,AVAILABLE}
[junit4] 2> 1545505 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7bed5495{/solr,null,AVAILABLE}
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2fbc243b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49341}
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.e.j.s.Server Started @1498377ms
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49341}
[junit4] 2> 1545506 ERROR (jetty-launcher-13172-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-18T21:46:18.543856900Z
[junit4] 2> 1545506 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@40e98740{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49340}
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.e.j.s.Server Started @1498381ms
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49340}
[junit4] 2> 1545510 ERROR (jetty-launcher-13172-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1545510 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-18T21:46:18.547518200Z
[junit4] 2> 1545512 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545515 INFO (zkConnectionManagerCallback-13176-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545515 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545515 INFO (zkConnectionManagerCallback-13174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545515 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1545515 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545515 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1545620 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1545627 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1545627 WARN (jetty-launcher-13172-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@196d7b19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545627 WARN (jetty-launcher-13172-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@196d7b19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545635 WARN (jetty-launcher-13172-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ff75f0a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545635 WARN (jetty-launcher-13172-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ff75f0a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545677 WARN (jetty-launcher-13172-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44fc0b7c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545677 WARN (jetty-launcher-13172-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44fc0b7c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545677 WARN (jetty-launcher-13172-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@72e5b7c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545677 WARN (jetty-launcher-13172-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@72e5b7c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1545677 INFO (jetty-launcher-13172-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49326/solr
[junit4] 2> 1545677 INFO (jetty-launcher-13172-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49326/solr
[junit4] 2> 1545688 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545688 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545694 INFO (zkConnectionManagerCallback-13198-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545694 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545694 INFO (zkConnectionManagerCallback-13196-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545694 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545864 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49341_solr
[junit4] 2> 1545865 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.Overseer Overseer (id=72066113352499205-127.0.0.1:49341_solr-n_0000000000) starting
[junit4] 2> 1545880 INFO (OverseerStateUpdate-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [n:127.0.0.1:49341_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49341_solr
[junit4] 2> 1545882 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49341_solr
[junit4] 2> 1545886 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1545893 INFO (OverseerStateUpdate-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1545893 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1545893 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1545893 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1545924 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1545924 WARN (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_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> 1545933 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:49341_solr]
[junit4] 2> 1545934 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1545935 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1545935 DEBUG (ScheduledTrigger-13205-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1545950 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1545977 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49341.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1545991 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49341.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1545991 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49341.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1545997 INFO (jetty-launcher-13172-thread-2) [n:127.0.0.1:49341_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node2\.
[junit4] 2> 1546199 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1546227 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:49340_solr as DOWN
[junit4] 2> 1546231 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1546231 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49340_solr
[junit4] 2> 1546238 INFO (zkCallback-13195-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1546239 INFO (zkCallback-13197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1546242 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1546242 WARN (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_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> 1546269 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1546306 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49340.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1546310 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49340.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1546310 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49340.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1546310 INFO (jetty-launcher-13172-thread-1) [n:127.0.0.1:49340_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node1\.
[junit4] 2> 1546366 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1546369 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1546373 INFO (zkConnectionManagerCallback-13223-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1546373 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1546375 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1546385 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49326/solr ready
[junit4] 2> 1546395 INFO (qtp561765988-23036) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1546404 INFO (qtp1311662325-23033) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1546411 INFO (qtp561765988-23037) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1546411 DEBUG (qtp1311662325-23034) [n:127.0.0.1:49340_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1546413 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 1546413 DEBUG (zkCallback-13195-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1546415 INFO (qtp1311662325-23035) [n:127.0.0.1:49340_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1546415 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1546424 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1546427 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1546431 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1546442 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1546449 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1546453 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1546457 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1546464 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1546471 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1546481 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1546485 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1546488 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1546497 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1546527 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1546536 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1546537 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1546541 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1546542 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1546545 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1546548 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1546549 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1546552 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1546562 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1546572 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1546579 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1546580 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1546593 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1546598 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1546610 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1546616 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1546620 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1546629 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1546637 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1546647 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1546654 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1546656 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1546660 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1546661 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1546664 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1546668 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1546676 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1546677 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1546677 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1546678 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1546678 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1546682 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1546685 INFO (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1546755 DEBUG (ScheduledTrigger-13205-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1546780 WARN (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection testScheduledTrigger is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1546783 DEBUG (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1546788 INFO (qtp1311662325-23033) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=4
[junit4] 2> 1546796 INFO (qtp561765988-23031) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1546796 DEBUG (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1546802 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1546806 INFO (qtp561765988-23036) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1546817 DEBUG (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 129996278 sessionWrapper.createTime 129996252941100, this.sessionWrapper.createTime 129996252941100
[junit4] 2> 1546827 INFO (OverseerStateUpdate-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [n:127.0.0.1:49341_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49341/solr",
[junit4] 2> "node_name":"127.0.0.1:49341_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1546847 INFO (OverseerStateUpdate-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [n:127.0.0.1:49341_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49340/solr",
[junit4] 2> "node_name":"127.0.0.1:49340_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1546862 INFO (OverseerStateUpdate-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [n:127.0.0.1:49341_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49340/solr",
[junit4] 2> "node_name":"127.0.0.1:49340_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1547031 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1547031 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1547031 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1547031 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1547062 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1547067 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1547067 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1547090 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547114 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547126 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547332 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1547332 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n1' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1547332 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49341.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1547332 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1547336 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1547336 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n4' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1547337 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49340.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1547337 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1547345 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1547345 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n2' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1547345 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49340.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1547345 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1547414 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1547414 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1547416 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1547416 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1547417 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1547417 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547419 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1547419 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547424 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1547424 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1547425 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547426 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547426 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547426 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1547426 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547426 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1547427 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1547427 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1547427 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547427 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547427 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 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> 1547427 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1547427 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547427 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_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> 1547428 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1547428 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667066369634467840
[junit4] 2> 1547428 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1547428 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667066369634467840
[junit4] 2> 1547430 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547430 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1547430 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1547430 INFO (searcherExecutor-13225-thread-1-processing-n:127.0.0.1:49341_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547430 INFO (searcherExecutor-13225-thread-1-processing-n:127.0.0.1:49341_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1547430 INFO (searcherExecutor-13227-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547430 INFO (searcherExecutor-13227-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1547431 INFO (searcherExecutor-13225-thread-1-processing-n:127.0.0.1:49341_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1547431 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1547431 INFO (searcherExecutor-13227-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n4] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1547431 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547431 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 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> 1547432 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1547433 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667066369639710720
[junit4] 2> 1547434 INFO (searcherExecutor-13229-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547434 INFO (searcherExecutor-13229-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1547434 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1547434 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1547434 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1547434 INFO (searcherExecutor-13229-thread-1-processing-n:127.0.0.1:49340_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1547435 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0}, version=1}
[junit4] 2> 1547435 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1547436 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1547436 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1547442 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1547442 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1547442 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49341/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1547443 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:49341/solr START replicas=[http://127.0.0.1:49340/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:49340/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1547443 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:49341/solr DONE. We have no versions. sync failed.
[junit4] 2> 1547444 INFO (qtp1311662325-23033) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1547444 INFO (qtp1311662325-23100) [n:127.0.0.1:49340_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1547445 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1547445 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1547445 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72066113352499205-core_node3-n_0000000000
[junit4] 2> 1547445 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49341/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1547533 INFO (zkCallback-13195-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1547533 INFO (zkCallback-13195-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1547533 DEBUG (ScheduledTrigger-13205-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> 1547533 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1547536 INFO (qtp561765988-23025) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=671
[junit4] 2> 1547611 INFO (zkCallback-13195-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1547611 INFO (zkCallback-13195-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1547611 INFO (zkCallback-13195-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548191 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1560
[junit4] 2> 1548193 INFO (qtp1311662325-23032) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1564
[junit4] 2> 1548194 DEBUG (OverseerThreadFactory-13207-thread-1-processing-n:127.0.0.1:49341_solr) [n:127.0.0.1:49341_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1548195 INFO (qtp1311662325-23035) [n:127.0.0.1:49340_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> 1548269 DEBUG (ScheduledTrigger-13205-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> 1548276 INFO (zkCallback-13195-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548276 INFO (zkCallback-13195-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548276 INFO (zkCallback-13197-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548276 INFO (zkCallback-13195-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548276 INFO (zkCallback-13197-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548277 INFO (qtp1311662325-23035) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=2430
[junit4] 2> 1548277 INFO (zkCallback-13197-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1548278 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1548295 INFO (qtp1311662325-23100) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n2:INDEX.sizeInBytes&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1548301 INFO (qtp1311662325-23034) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=4
[junit4] 2> 1548301 INFO (qtp561765988-23037) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1548339 INFO (qtp561765988-23031) [n:127.0.0.1:49341_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=45
[junit4] 2> 1548339 DEBUG (qtp1311662325-23033) [n:127.0.0.1:49340_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1548340 INFO (qtp1311662325-23033) [n:127.0.0.1:49340_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=72
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1548342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1548343 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c22af38{/solr,null,AVAILABLE}
[junit4] 2> 1548350 DEBUG (zkCallback-13195-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1548350 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2f7f0ac3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49433}
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.s.Server Started @1502017ms
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49433}
[junit4] 2> 1548351 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1548351 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1548351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-18T21:46:21.388678800Z
[junit4] 2> 1548351 DEBUG (OverseerAutoScalingTriggerThread-72066113352499205-127.0.0.1:49341_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1548352 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1548357 INFO (zkConnectionManagerCallback-13243-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1548357 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1548360 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1548445 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1548450 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@307b2981[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1548450 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@307b2981[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1548460 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2f6ed5a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1548460 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2f6ed5a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1548460 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49326/solr
[junit4] 2> 1548466 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1548484 INFO (zkConnectionManagerCallback-13254-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1548484 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1548585 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1548594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:49433_solr as DOWN
[junit4] 2> 1548595 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1548595 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49433_solr
[junit4] 2> 1548597 INFO (zkCallback-13195-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1548597 INFO (zkCallback-13222-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1548597 INFO (zkCallback-13253-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1548597 INFO (zkCallback-13197-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1548599 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1548600 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_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> 1548615 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1548641 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49433.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1bfd10c2
[junit4] 2> 1548651 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [n:127.0.0.1:49433_solr ] o.a.s.m.r.SolrJmxRepo
[...truncated too long message...]
pache.solr.metrics.reporters.SolrJmxReporter@1232b561: rootName = solr_49340, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@797d4ec7
[junit4] 2> 1589155 INFO (jetty-closer-13263-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1589229 INFO (jetty-closer-13263-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2f7f0ac3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1589230 INFO (jetty-closer-13263-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6c22af38{/solr,null,UNAVAILABLE}
[junit4] 2> 1589230 INFO (jetty-closer-13263-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1589243 INFO (jetty-closer-13263-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72066113352499205-127.0.0.1:49341_solr-n_0000000000) closing
[junit4] 2> 1589245 INFO (jetty-closer-13263-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2fbc243b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1589246 INFO (zkCallback-13197-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1589247 INFO (jetty-closer-13263-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7bed5495{/solr,null,UNAVAILABLE}
[junit4] 2> 1589247 INFO (jetty-closer-13263-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1589358 INFO (jetty-closer-13263-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@40e98740{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1589358 INFO (jetty-closer-13263-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28cf4f45{/solr,null,UNAVAILABLE}
[junit4] 2> 1589358 INFO (jetty-closer-13263-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1589359 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1589579 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 29 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1589585 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:49326
[junit4] 2> 1589585 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49326
[junit4] 2> 1589586 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49326
[junit4] 2> 1591744 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[D965585A25D9D470]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=D965585A25D9D470 -Dtests.slow=true -Dtests.locale=sr-Cyrl-XK -Dtests.timezone=America/Guatemala -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 46.5s J4 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([D965585A25D9D470:4A7E10287B248F44]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_D965585A25D9D470-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1097, maxMBSortInHeap=7.264596008668602, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@14a42178), locale=sr-Cyrl-XK, timezone=America/Guatemala
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=191373688,total=510132224
[junit4] 2> NOTE: All tests run in this JVM: [MetricUtilsTest, TestFieldTypeResource, MoreLikeThisComponentTest, RuleEngineTest, AtomicUpdatesTest, DistributedFacetExistsSmallTest, TestValueSourceCache, TestHashQParserPlugin, TestIBSimilarityFactory, TestReplicationHandlerDiskOverFlow, UtilsToolTest, TestSolrJacksonAnnotation, ConcurrentCreateCollectionTest, TestEmbeddedSolrServerSchemaAPI, PackageManagerCLITest, TestConfigReload, NodeAddedTriggerIntegrationTest, TestUtilizeNode, WordBreakSolrSpellCheckerTest, SubstringBytesRefFilterTest, SimpleMLTQParserTest, TestClusterStateMutator, TestSha256AuthenticationProvider, TestComponentsName, RestoreTriggerStateTest, TestFunctionQuery, HdfsThreadLeakTest, LargeFieldTest, SampleTest, TestFieldCacheWithThreads, TestClusterProperties, LukeRequestHandlerTest, TestSerializedLuceneMatchVersion, TestDynamicFieldCollectionResource, TestExpandComponent, CloneFieldUpdateProcessorFactoryTest, TestSolrCachePerf, PKIAuthenticationIntegrationTest, TestRTGBase, TestWordDelimiterFilterFactory, BJQParserTest, DistribDocExpirationUpdateProcessorTest, TestExactSharedStatsCacheCloud, TestNumericRangeQuery32, OutOfBoxZkACLAndCredentialsProvidersTest, TestAddFieldRealTimeGet, AuditLoggerPluginTest, HDFSCollectionsAPITest, ReturnFieldsTest, AddBlockUpdateTest, HdfsNNFailoverTest, TestChildDocTransformerHierarchy, SolrRequestParserTest, CacheHeaderTest, ConnectionManagerTest, RAMDirectoryFactoryTest, SearchHandlerTest, TestManagedResource, TestOmitPositions, IndexSizeTriggerMixedBoundsTest, JSONWriterTest, CircularListTest, DistributedSpellCheckComponentTest, AutoScalingHandlerTest, CurrencyFieldTypeTest, ManagedSchemaRoundRobinCloudTest, TriggerIntegrationTest, TestLocalFSCloudBackupRestore, UUIDUpdateProcessorFallbackTest, TestCoreAdminApis, SimpleCollectionCreateDeleteTest, RemoteQueryErrorTest, TestSolrConfigHandlerCloud, MetricsHistoryWithAuthIntegrationTest, TestStreamBody, TestAnalyzeInfixSuggestions, FieldMutatingUpdateProcessorTest, BufferStoreTest, TestBinaryField, SolrXmlInZkTest, ProtectedTermFilterFactoryTest, CleanupOldIndexTest, CustomHighlightComponentTest, DirectoryFactoryTest, TestJsonFacets, TestCollationFieldDocValues, MoveReplicaTest, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, ActionThrottleTest, AliasIntegrationTest, ClusterStateTest, CollectionPropsTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DistributedQueueTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, OverseerSolrResponseTest, PeerSyncReplicationTest, TestDistributedMap, TestHashPartitioner, TestLRUStatsCacheCloud, TestLeaderElectionWithEmptyReplica, TestQueryingOnDownCollection, TestRandomRequestDistribution, TestSolrCloudWithKerberosAlt, TestStressLiveNodes, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [601/912 (1!)] on J4 in 46.50s, 1 test, 1 failure <<< FAILURES!
[...truncated 45859 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8849/consoleText
[repro] Revision: d2825282c984a7eca102d74f16c28eed5a4dda92
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3156 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=D965585A25D9D470 -Dtests.slow=true -Dtests.locale=sr-Cyrl-XK -Dtests.timezone=America/Guatemala -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 85 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-14) - Build # 8850
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8850/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testAutoAddReplicas
Error Message:
OverseerTriggerThread never caught up to the latest znodeVersion
Stack Trace:
java.util.concurrent.TimeoutException: OverseerTriggerThread never caught up to the latest znodeVersion
at __randomizedtesting.SeedInfo.seed([9A7AFB32A3427080:13DA084ED749BCA9]:0)
at org.apache.solr.util.TimeOut.waitFor(TimeOut.java:66)
at org.apache.solr.cloud.autoscaling.sim.SimScenario$LoadAutoscaling.execute(SimScenario.java:466)
at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1110)
at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testAutoAddReplicas(TestSimScenario.java:58)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 14919 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[junit4] 2> 1386761 INFO (SUITE-TestSimScenario-seed#[9A7AFB32A3427080]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1386761 INFO (SUITE-TestSimScenario-seed#[9A7AFB32A3427080]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1386762 INFO (SUITE-TestSimScenario-seed#[9A7AFB32A3427080]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.autoscaling.sim.TestSimScenario_9A7AFB32A3427080-001\data-dir-113-001
[junit4] 2> 1386762 INFO (SUITE-TestSimScenario-seed#[9A7AFB32A3427080]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1386763 INFO (SUITE-TestSimScenario-seed#[9A7AFB32A3427080]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1386765 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSuggestions
[junit4] 2> 1386774 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 1386775 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 1386782 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10072_solr
[junit4] 2> 1386782 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 1386782 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 1386782 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1386782 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 1386782 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1386783 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1386783 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10073_solr, 127.0.0.1:10072_solr]
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 1
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1386783 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1386783 DEBUG (ScheduledTrigger-11715-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> 1386783 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 3. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 1386783 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 1386783 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 1386783 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 1386783 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 1386793 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 1386793 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 1386794 DEBUG (simCloudManagerPool-11714-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=3
[junit4] 2> 1386794 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 4. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 1386794 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 1386801 DEBUG (ScheduledTrigger-11715-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> 1386805 DEBUG (simCloudManagerPool-11714-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 1386805 DEBUG (simCloudManagerPool-11714-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 1386805 DEBUG (simCloudManagerPool-11714-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 1386805 DEBUG (simCloudManagerPool-11714-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=4): {"core_node2":{
[junit4] 2> "core":"testCollection_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10073_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1386815 DEBUG (simCloudManagerPool-11714-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 1386815 DEBUG (simCloudManagerPool-11714-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 1386821 DEBUG (ScheduledTrigger-11715-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386825 DEBUG (simCloudManagerPool-11714-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 1386825 DEBUG (simCloudManagerPool-11714-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 1386825 DEBUG (simCloudManagerPool-11714-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 1386825 DEBUG (simCloudManagerPool-11714-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=6): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10072_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1386825 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 1386825 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 1386836 DEBUG (simCloudManagerPool-11714-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 1386836 DEBUG (simCloudManagerPool-11714-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 1386836 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 1386836 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 1386836 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 5. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 1386836 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10073_solr
[junit4] 2> 1386836 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 1386836 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10073_solr
[junit4] 2> 1386837 INFO (simCloudManagerPool-11714-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10073_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1386837 INFO (simCloudManagerPool-11714-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1386840 DEBUG (ScheduledTrigger-11715-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386848 DEBUG (simCloudManagerPool-11714-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 1386848 DEBUG (simCloudManagerPool-11714-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 1386848 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 7. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 1386848 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10073_solr
[junit4] 2> 1386849 DEBUG (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 1386849 DEBUG (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 1386849 INFO (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10073_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1386849 INFO (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1386860 DEBUG (ScheduledTrigger-11715-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386860 DEBUG (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 1386860 DEBUG (simCloudManagerPool-11714-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 8. LoopOp iterations=${iterative}
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=0
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 9. LoopOp iterations=${justCalc}
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=1
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 1 :
[junit4] 2> 1386860 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1386860 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 1386860 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 1386867 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1386867 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - SaveSnapshot path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.cloud.autoscaling.sim.TestSimScenario_9A7AFB32A3427080-001\tempDir-001/snapshot/0)
[junit4] 2> 1386870 WARN (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimUtils V2 action SET_CLUSTER_PROPERTY_OBJ has no equivalent V1 action
[junit4] 2> 1386870 WARN (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimUtils V2 action GET_NODES has no equivalent V1 action
[junit4] 2> 1386874 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 1386878 DEBUG (ScheduledTrigger-11715-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386894 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 10. Dump redact=true
[junit4] 2> 1386894 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario Dump redact=true
[junit4] 2> 1386894 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 1386896 DEBUG (ScheduledTrigger-11715-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386898 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1386899 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 1386899 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 1386899 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 1386899 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 1386900 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1386900 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1386900 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 1386900 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 1386906 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10074_solr
[junit4] 2> 1386906 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 1386906 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 1386906 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1386906 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1386906 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1386906 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10075_solr, 127.0.0.1:10074_solr]
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 1
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1386906 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1386906 DEBUG (ScheduledTrigger-11721-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> 1386915 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 3. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 1386915 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 1386915 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 1386915 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 1386915 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 1386925 DEBUG (ScheduledTrigger-11721-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> 1386926 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 1386926 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 1386926 DEBUG (simCloudManagerPool-11720-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=3
[junit4] 2> 1386926 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 4. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 1386926 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 1386937 DEBUG (simCloudManagerPool-11720-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 1386937 DEBUG (simCloudManagerPool-11720-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 1386937 DEBUG (simCloudManagerPool-11720-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 1386937 DEBUG (simCloudManagerPool-11720-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=4): {"core_node1":{
[junit4] 2> "core":"testCollection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1386937 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 1386937 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 1386937 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.CloudUtil -- shards without leaders: [shard2]
[junit4] 2> 1386946 DEBUG (ScheduledTrigger-11721-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> 1386948 DEBUG (simCloudManagerPool-11720-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 1386948 DEBUG (simCloudManagerPool-11720-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 1386958 DEBUG (simCloudManagerPool-11720-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 1386958 DEBUG (simCloudManagerPool-11720-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 1386958 DEBUG (simCloudManagerPool-11720-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 1386958 DEBUG (simCloudManagerPool-11720-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=7): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1386965 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1386968 DEBUG (simCloudManagerPool-11720-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 1386968 DEBUG (simCloudManagerPool-11720-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 1386968 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 1386969 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 1386969 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 5. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 1386969 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10074_solr
[junit4] 2> 1386969 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 1386969 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr
[junit4] 2> 1386969 INFO (simCloudManagerPool-11720-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1386969 INFO (simCloudManagerPool-11720-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1386980 DEBUG (simCloudManagerPool-11720-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 1386980 DEBUG (simCloudManagerPool-11720-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 1386980 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 7. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 1386980 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr
[junit4] 2> 1386980 DEBUG (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 1386980 DEBUG (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 1386980 INFO (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1386981 INFO (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1386982 DEBUG (ScheduledTrigger-11721-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> 1386990 DEBUG (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 1386990 DEBUG (simCloudManagerPool-11720-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 1386990 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 8. LoopOp iterations=${iterative}
[junit4] 2> 1386990 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=10
[junit4] 2> 1386990 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 1 :
[junit4] 2> 1386990 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1386990 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 1386991 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 1386991 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1386991 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1387001 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387020 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387038 DEBUG (ScheduledTrigger-11721-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> 1387059 DEBUG (ScheduledTrigger-11721-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> 1387079 DEBUG (ScheduledTrigger-11721-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> 1387082 DEBUG (simCloudManagerPool-11720-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node10":{
[junit4] 2> "core":"testCollection_shard2_replica_n9",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1387097 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387116 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387135 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387154 DEBUG (ScheduledTrigger-11721-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> 1387172 DEBUG (ScheduledTrigger-11721-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> 1387183 DEBUG (simCloudManagerPool-11720-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=13
[junit4] 2> 1387183 DEBUG (simCloudManagerPool-11720-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 1387183 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387183 DEBUG (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 1387183 DEBUG (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 1387183 INFO (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387184 INFO (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387191 DEBUG (ScheduledTrigger-11721-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> 1387193 DEBUG (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=15
[junit4] 2> 1387193 DEBUG (simCloudManagerPool-11720-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 1387193 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387194 DEBUG (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=16
[junit4] 2> 1387194 DEBUG (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 16
[junit4] 2> 1387194 INFO (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387194 INFO (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387204 DEBUG (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=17
[junit4] 2> 1387204 DEBUG (simCloudManagerPool-11720-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 17
[junit4] 2> 1387204 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 2 :
[junit4] 2> 1387204 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1387204 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=18
[junit4] 2> 1387205 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 18
[junit4] 2> 1387205 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1387205 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1387209 DEBUG (ScheduledTrigger-11721-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> 1387228 DEBUG (ScheduledTrigger-11721-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> 1387246 DEBUG (ScheduledTrigger-11721-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> 1387265 DEBUG (ScheduledTrigger-11721-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> 1387283 DEBUG (ScheduledTrigger-11721-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> 1387295 DEBUG (simCloudManagerPool-11720-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node16":{
[junit4] 2> "core":"testCollection_shard1_replica_n15",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 1387302 DEBUG (ScheduledTrigger-11721-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> 1387320 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387338 DEBUG (ScheduledTrigger-11721-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> 1387357 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387377 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387394 DEBUG (simCloudManagerPool-11720-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=19
[junit4] 2> 1387394 DEBUG (simCloudManagerPool-11720-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 19
[junit4] 2> 1387394 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387394 DEBUG (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=20
[junit4] 2> 1387395 DEBUG (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 20
[junit4] 2> 1387395 INFO (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387395 INFO (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387397 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387404 DEBUG (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=21
[junit4] 2> 1387404 DEBUG (simCloudManagerPool-11720-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 21
[junit4] 2> 1387404 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387405 DEBUG (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=22
[junit4] 2> 1387405 DEBUG (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 22
[junit4] 2> 1387405 INFO (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387405 INFO (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387414 DEBUG (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=23
[junit4] 2> 1387414 DEBUG (simCloudManagerPool-11720-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 23
[junit4] 2> 1387414 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 3 :
[junit4] 2> 1387414 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1387414 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=24
[junit4] 2> 1387414 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 24
[junit4] 2> 1387414 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1387414 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1387415 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387433 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387452 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387470 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387489 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387504 DEBUG (simCloudManagerPool-11720-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node22":{
[junit4] 2> "core":"testCollection_shard1_replica_n21",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1387510 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387529 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387548 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387567 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387586 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387603 DEBUG (simCloudManagerPool-11720-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=25
[junit4] 2> 1387603 DEBUG (simCloudManagerPool-11720-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 25
[junit4] 2> 1387603 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387604 DEBUG (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=26
[junit4] 2> 1387604 DEBUG (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 26
[junit4] 2> 1387604 INFO (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387604 INFO (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387606 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387615 DEBUG (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=27
[junit4] 2> 1387615 DEBUG (simCloudManagerPool-11720-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 27
[junit4] 2> 1387615 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387615 DEBUG (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=28
[junit4] 2> 1387615 DEBUG (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 28
[junit4] 2> 1387615 INFO (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387615 INFO (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387624 DEBUG (ScheduledTrigger-11721-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> 1387626 DEBUG (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=29
[junit4] 2> 1387626 DEBUG (simCloudManagerPool-11720-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 29
[junit4] 2> 1387626 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 4 :
[junit4] 2> 1387626 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1387626 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=30
[junit4] 2> 1387626 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 30
[junit4] 2> 1387626 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1387626 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1387642 DEBUG (ScheduledTrigger-11721-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> 1387661 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387680 DEBUG (ScheduledTrigger-11721-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> 1387698 DEBUG (ScheduledTrigger-11721-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> 1387717 DEBUG (ScheduledTrigger-11721-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> 1387717 DEBUG (simCloudManagerPool-11720-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node28":{
[junit4] 2> "core":"testCollection_shard1_replica_n27",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1387736 DEBUG (ScheduledTrigger-11721-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> 1387754 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387772 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387791 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387810 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387817 DEBUG (simCloudManagerPool-11720-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=31
[junit4] 2> 1387817 DEBUG (simCloudManagerPool-11720-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 31
[junit4] 2> 1387817 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387817 DEBUG (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=32
[junit4] 2> 1387818 DEBUG (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 32
[junit4] 2> 1387818 INFO (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387818 INFO (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387828 DEBUG (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=33
[junit4] 2> 1387828 DEBUG (simCloudManagerPool-11720-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 33
[junit4] 2> 1387828 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1387828 DEBUG (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=34
[junit4] 2> 1387828 DEBUG (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 34
[junit4] 2> 1387828 INFO (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1387828 INFO (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1387829 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387840 DEBUG (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=35
[junit4] 2> 1387840 DEBUG (simCloudManagerPool-11720-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 35
[junit4] 2> 1387840 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 5 :
[junit4] 2> 1387840 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1387840 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=36
[junit4] 2> 1387840 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 36
[junit4] 2> 1387840 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1387840 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1387847 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387866 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387884 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387902 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387924 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387931 DEBUG (simCloudManagerPool-11720-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node34":{
[junit4] 2> "core":"testCollection_shard1_replica_n33",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1387942 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387960 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1387979 DEBUG (ScheduledTrigger-11721-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> 1387997 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388016 DEBUG (ScheduledTrigger-11721-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> 1388033 DEBUG (simCloudManagerPool-11720-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=37
[junit4] 2> 1388033 DEBUG (simCloudManagerPool-11720-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 37
[junit4] 2> 1388034 DEBUG (MetricsHistoryHandler-11724-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=38
[junit4] 2> 1388034 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388034 DEBUG (MetricsHistoryHandler-11724-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 38
[junit4] 2> 1388034 INFO (simCloudManagerPool-11720-thread-21) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388034 INFO (simCloudManagerPool-11720-thread-21) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388034 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388044 DEBUG (simCloudManagerPool-11720-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=39
[junit4] 2> 1388044 DEBUG (simCloudManagerPool-11720-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 39
[junit4] 2> 1388044 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388045 DEBUG (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=40
[junit4] 2> 1388045 DEBUG (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 40
[junit4] 2> 1388045 INFO (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388045 INFO (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388053 DEBUG (ScheduledTrigger-11721-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> 1388055 DEBUG (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=41
[junit4] 2> 1388055 DEBUG (simCloudManagerPool-11720-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 41
[junit4] 2> 1388055 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 6 :
[junit4] 2> 1388055 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1388055 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=42
[junit4] 2> 1388055 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 42
[junit4] 2> 1388055 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1388055 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1388073 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388091 DEBUG (ScheduledTrigger-11721-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> 1388109 DEBUG (ScheduledTrigger-11721-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> 1388128 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388146 DEBUG (simCloudManagerPool-11720-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node40":{
[junit4] 2> "core":"testCollection_shard1_replica_n39",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1388147 DEBUG (ScheduledTrigger-11721-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> 1388166 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388186 DEBUG (ScheduledTrigger-11721-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> 1388205 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388224 DEBUG (ScheduledTrigger-11721-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> 1388243 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388244 DEBUG (simCloudManagerPool-11720-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=43
[junit4] 2> 1388244 DEBUG (simCloudManagerPool-11720-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 43
[junit4] 2> 1388244 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388245 DEBUG (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=44
[junit4] 2> 1388245 DEBUG (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 44
[junit4] 2> 1388245 INFO (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388245 INFO (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388255 DEBUG (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=45
[junit4] 2> 1388255 DEBUG (simCloudManagerPool-11720-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 45
[junit4] 2> 1388255 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388255 DEBUG (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=46
[junit4] 2> 1388255 DEBUG (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 46
[junit4] 2> 1388255 INFO (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388255 INFO (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388261 DEBUG (ScheduledTrigger-11721-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> 1388265 DEBUG (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=47
[junit4] 2> 1388265 DEBUG (simCloudManagerPool-11720-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 47
[junit4] 2> 1388265 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 7 :
[junit4] 2> 1388265 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1388265 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=48
[junit4] 2> 1388265 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 48
[junit4] 2> 1388265 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1388265 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1388279 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388298 DEBUG (ScheduledTrigger-11721-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> 1388318 DEBUG (ScheduledTrigger-11721-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> 1388336 DEBUG (ScheduledTrigger-11721-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> 1388356 DEBUG (ScheduledTrigger-11721-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> 1388357 DEBUG (simCloudManagerPool-11720-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node46":{
[junit4] 2> "core":"testCollection_shard1_replica_n45",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1388375 DEBUG (ScheduledTrigger-11721-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> 1388393 DEBUG (ScheduledTrigger-11721-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> 1388412 DEBUG (ScheduledTrigger-11721-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388432 DEBUG (ScheduledTrigger-11721-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> 1388450 DEBUG (ScheduledTrigger-11721-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> 1388456 DEBUG (simCloudManagerPool-11720-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=49
[junit4] 2> 1388456 DEBUG (simCloudManagerPool-11720-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 49
[junit4] 2> 1388457 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388457 DEBUG (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=50
[junit4] 2> 1388457 DEBUG (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 50
[junit4] 2> 1388457 INFO (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388457 INFO (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388467 DEBUG (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=51
[junit4] 2> 1388467 DEBUG (simCloudManagerPool-11720-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 51
[junit4] 2> 1388467 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388467 DEBUG (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=52
[junit4] 2> 1388467 DEBUG (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 52
[junit4] 2> 1388467 INFO (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388467 INFO (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388468 DEBUG (ScheduledTrigger-11721-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> 1388478 DEBUG (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=53
[junit4] 2> 1388478 DEBUG (simCloudManagerPool-11720-thread-28) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 53
[junit4] 2> 1388478 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario * iter 8 :
[junit4] 2> 1388478 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 1388478 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=54
[junit4] 2> 1388478 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 54
[junit4] 2> 1388478 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 1388478 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 1388487 DEBUG (ScheduledTrigger-11721-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> 1388505 DEBUG (ScheduledTrigger-11721-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> 1388524 DEBUG (ScheduledTrigger-11721-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> 1388543 DEBUG (ScheduledTrigger-11721-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> 1388562 DEBUG (ScheduledTrigger-11721-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> 1388569 DEBUG (simCloudManagerPool-11720-thread-29) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node52":{
[junit4] 2> "core":"testCollection_shard1_replica_n51",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10075_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "node":"127.0.0.1:10074_solr",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10074_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 1388580 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388599 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388617 DEBUG (ScheduledTrigger-11721-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> 1388635 DEBUG (ScheduledTrigger-11721-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> 1388654 DEBUG (ScheduledTrigger-11721-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> 1388670 DEBUG (simCloudManagerPool-11720-thread-29) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=55
[junit4] 2> 1388670 DEBUG (simCloudManagerPool-11720-thread-29) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 55
[junit4] 2> 1388670 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388671 DEBUG (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=56
[junit4] 2> 1388671 DEBUG (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 56
[junit4] 2> 1388671 INFO (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10074_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 1388671 INFO (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1388672 DEBUG (ScheduledTrigger-11721-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1388681 DEBUG (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=57
[junit4] 2> 1388681 DEBUG (simCloudManagerPool-11720-thread-30) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 57
[junit4] 2> 1388681 INFO (TEST-TestSimScenario.testSuggestions-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10074_solr)
[junit4] 2> 1388681 DEBUG (simCloudManagerPool-11720-thread-31) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersi
[...truncated too long message...]
estCollection_shard1_replica_n5",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10106_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"down",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 18001 DEBUG (simCloudManagerPool-27-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 18001 DEBUG (simCloudManagerPool-27-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 18053 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=MOVEREPLICA&collection=testCollection&targetNode=127.0.0.1:10106_solr&inPlaceMove=true&replica=core_node4
[junit4] 2> 18057 DEBUG (simCloudManagerPool-27-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 18057 DEBUG (simCloudManagerPool-27-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 18172 DEBUG (simCloudManagerPool-27-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node8":{
[junit4] 2> "core":"testCollection_shard2_replica_n7",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10106_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"down",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 18300 DEBUG (simCloudManagerPool-27-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=13
[junit4] 2> 18301 DEBUG (simCloudManagerPool-27-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 18312 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 18312 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 18312 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"8777a2e073dcTbvynv697fzow2td2bxqt8285u",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":148947903476700,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[148947903476700],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":148957959406700,
[junit4] 2> "nodeNames":["127.0.0.1:10107_solr"]}}
[junit4] 2> 18312 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 100ms
[junit4] 2> 18313 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 18313 DEBUG (AutoscalingActionExecutor-29-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 24828 ms for event id=8777a2e073dcTbvynv697fzow2td2bxqt8285u
[junit4] 2> 18313 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 8. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 18313 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 18313 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario 9. SaveSnapshot path=${snapshotPath}
[junit4] 2> 18313 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SimScenario SaveSnapshot path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_9A7AFB32A3427080-001\tempDir-002/snapshot
[junit4] 2> 18313 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 23 resources from SimDistribStateManager
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 18336 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 18336 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 18340 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - loaded snapshot of 23 resources
[junit4] 2> 18340 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[9A7AFB32A3427080]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAutoAddReplicas
[junit4] OK 0.95s J1 | TestSimScenario.testAutoAddReplicas
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_9A7AFB32A3427080-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1667, maxMBSortInHeap=6.123476935304619, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4dee2400), locale=en-TC, timezone=America/Glace_Bay
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=2,free=145452248,total=194707456
[junit4] 2> NOTE: All tests run in this JVM: [TestSimScenario]
[junit4] Completed [1/5 (1!)] on J1 in 17.30s, 4 tests, 1 failure <<< FAILURES!
[...truncated 41 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1521: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1048: There were test failures: 5 suites, 20 tests, 1 failure [seed: 9A7AFB32A3427080]
Total time: 24 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 1/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=9259f00748de2d056850526a85f144ae07f70ed3, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #8849
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2