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/27 08:39:40 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-13.0.2) - Build # 1180
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1180/
Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
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([BABC3D4BA28C313D:29A77539FC716A09]: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:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.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:368)
at java.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 15386 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1376514 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[BABC3D4BA28C313D]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1376514 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[BABC3D4BA28C313D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1376515 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[BABC3D4BA28C313D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\data-dir-111-001
[junit4] 2> 1376515 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[BABC3D4BA28C313D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1376517 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[BABC3D4BA28C313D]-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> 1376519 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1376520 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001
[junit4] 2> 1376521 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1376521 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1376521 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1376660 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer start zk server on port: 49955
[junit4] 2> 1376660 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49955
[junit4] 2> 1376660 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49955
[junit4] 2> 1376660 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49955
[junit4] 2> 1376661 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1376666 INFO (zkConnectionManagerCallback-14848-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1376666 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1376669 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1376671 INFO (zkConnectionManagerCallback-14850-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1376671 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1376672 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1376675 INFO (zkConnectionManagerCallback-14852-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1376675 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1376820 WARN (jetty-launcher-14853-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1376820 WARN (jetty-launcher-14853-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c766730{/solr,null,AVAILABLE}
[junit4] 2> 1376820 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@33bcddd7{/solr,null,AVAILABLE}
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@adecd87{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49968}
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.e.j.s.Server Started @1329237ms
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49968}
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e73be4c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49969}
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-1) [ ] o.e.j.s.Server Started @1329237ms
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49969}
[junit4] 2> 1376823 ERROR (jetty-launcher-14853-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-27T08:09:24.412581Z
[junit4] 2> 1376823 ERROR (jetty-launcher-14853-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1376823 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-27T08:09:24.413274200Z
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1376824 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1376827 INFO (zkConnectionManagerCallback-14857-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1376827 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1376827 INFO (zkConnectionManagerCallback-14855-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1376827 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1376828 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1376828 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1377018 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1377020 WARN (jetty-launcher-14853-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@539ab936[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377020 WARN (jetty-launcher-14853-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@539ab936[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377022 WARN (jetty-launcher-14853-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4103a797[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377022 WARN (jetty-launcher-14853-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4103a797[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377023 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49955/solr
[junit4] 2> 1377024 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1377026 INFO (zkConnectionManagerCallback-14868-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1377026 INFO (jetty-launcher-14853-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1377252 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1377255 INFO (zkConnectionManagerCallback-14873-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1377255 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1377358 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1377360 WARN (jetty-launcher-14853-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@68106601[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377360 WARN (jetty-launcher-14853-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@68106601[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377364 WARN (jetty-launcher-14853-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2013e645[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377364 WARN (jetty-launcher-14853-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2013e645[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1377364 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49955/solr
[junit4] 2> 1377366 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1377368 INFO (zkConnectionManagerCallback-14883-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1377368 INFO (jetty-launcher-14853-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1377404 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49968_solr
[junit4] 2> 1377406 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.Overseer Overseer (id=72112233704849414-127.0.0.1:49968_solr-n_0000000000) starting
[junit4] 2> 1377411 INFO (OverseerStateUpdate-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [n:127.0.0.1:49968_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49968_solr
[junit4] 2> 1377413 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49968_solr
[junit4] 2> 1377414 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1377415 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1377415 INFO (zkCallback-14872-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1377415 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1377415 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1377417 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1377417 WARN (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_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> 1377420 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:49968_solr]
[junit4] 2> 1377421 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1377421 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1377421 DEBUG (ScheduledTrigger-14886-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> 1377446 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1377480 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49968.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377490 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49968.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377490 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49968.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377492 INFO (jetty-launcher-14853-thread-2) [n:127.0.0.1:49968_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node2\.
[junit4] 2> 1377509 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1377512 INFO (zkConnectionManagerCallback-14895-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1377512 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1377554 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1377561 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:49969_solr as DOWN
[junit4] 2> 1377563 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1377563 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49969_solr
[junit4] 2> 1377566 INFO (zkCallback-14872-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1377569 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1377569 WARN (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_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> 1377569 INFO (zkCallback-14894-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1377602 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1377628 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49969.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377635 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49969.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377635 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49969.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1377637 INFO (jetty-launcher-14853-thread-1) [n:127.0.0.1:49969_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node1\.
[junit4] 2> 1377654 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1377656 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1377657 INFO (zkConnectionManagerCallback-14908-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1377657 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1377657 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1377661 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49955/solr ready
[junit4] 2> 1377668 INFO (qtp68756340-23706) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1377669 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1377673 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1377678 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1377678 DEBUG (qtp68756340-23710) [n:127.0.0.1:49968_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1377678 INFO (qtp68756340-23710) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 1377678 DEBUG (zkCallback-14872-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1377681 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1377682 INFO (qtp548684842-23709) [n:127.0.0.1:49969_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> 1377684 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1377685 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1377686 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1377686 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1377689 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1377692 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377693 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377695 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377698 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377700 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377702 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377705 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377707 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377709 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377711 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377714 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377716 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377718 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377720 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377722 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377724 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377726 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377728 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377731 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377733 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377735 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377737 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377739 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377741 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377743 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377745 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377747 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377747 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377747 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377754 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377756 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377758 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377761 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377762 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377765 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377767 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377769 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377771 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377773 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377775 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1377776 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1377779 INFO (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1377923 WARN (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_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> 1377924 DEBUG (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1377929 INFO (qtp548684842-23703) [n:127.0.0.1:49969_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1377932 INFO (qtp68756340-23706) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1377932 DEBUG (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1377936 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1377936 INFO (qtp68756340-23708) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1377942 DEBUG (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 833736174 sessionWrapper.createTime 833736167232200, this.sessionWrapper.createTime 833736167232200
[junit4] 2> 1377947 INFO (OverseerStateUpdate-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [n:127.0.0.1:49968_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:49968/solr",
[junit4] 2> "node_name":"127.0.0.1:49968_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1377949 INFO (OverseerStateUpdate-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [n:127.0.0.1:49968_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:49969/solr",
[junit4] 2> "node_name":"127.0.0.1:49969_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1377952 INFO (OverseerStateUpdate-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [n:127.0.0.1:49968_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:49969/solr",
[junit4] 2> "node_name":"127.0.0.1:49969_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1378232 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378232 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378233 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378252 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1378253 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1378253 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1378277 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1378277 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1378280 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1378488 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378493 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378493 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378535 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378535 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49968.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1378536 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1378543 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378543 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49969.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1378543 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1378544 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378544 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_49969.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d5452f7
[junit4] 2> 1378544 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1378634 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378634 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378651 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378651 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1378657 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378657 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378657 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378657 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378657 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378657 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1378662 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378662 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1378662 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378662 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378662 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378662 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378662 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378662 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378662 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378662 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378662 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378668 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378668 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378668 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378668 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1378668 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1378668 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378668 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378668 INFO (qtp68756340-23704) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1378668 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378669 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378669 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378669 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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 1667830347210948608
[junit4] 2> 1378669 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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 1667830347210948608
[junit4] 2> 1378673 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378673 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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 1667830347215142912
[junit4] 2> 1378675 INFO (searcherExecutor-14914-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1378675 INFO (searcherExecutor-14914-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49969_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> 1378675 INFO (searcherExecutor-14914-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:49969_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> 1378675 INFO (searcherExecutor-14912-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1378675 INFO (searcherExecutor-14912-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49969_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> 1378676 INFO (searcherExecutor-14912-thread-1-processing-n:127.0.0.1:49969_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:49969_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> 1378680 INFO (searcherExecutor-14910-thread-1-processing-n:127.0.0.1:49968_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1378680 INFO (searcherExecutor-14910-thread-1-processing-n:127.0.0.1:49968_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49968_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> 1378682 INFO (searcherExecutor-14910-thread-1-processing-n:127.0.0.1:49968_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:49968_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> 1378683 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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_node5=0}, version=0}
[junit4] 2> 1378683 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1378683 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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_node5=0}, version=1}
[junit4] 2> 1378683 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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> 1378713 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1378713 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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> 1378719 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1378719 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1378719 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49969/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 1378720 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=http://127.0.0.1:49969/solr START replicas=[http://127.0.0.1:49968/solr/testScheduledTrigger_shard1_replica_n1/, http://127.0.0.1:49969/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1378720 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=http://127.0.0.1:49969/solr DONE. We have no versions. sync failed.
[junit4] 2> 1378723 INFO (qtp548684842-23703) [n:127.0.0.1:49969_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> 1378734 INFO (qtp68756340-23706) [n:127.0.0.1:49968_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1378734 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1378734 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 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> 1378734 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72112233704849416-core_node5-n_0000000000
[junit4] 2> 1378737 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:49969/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 1378808 DEBUG (ScheduledTrigger-14886-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> 1378878 INFO (zkCallback-14894-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> 1378878 INFO (zkCallback-14894-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> 1378879 INFO (qtp548684842-23705) [n:127.0.0.1:49969_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1378881 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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=470
[junit4] 2> 1379020 INFO (zkCallback-14894-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> 1379020 INFO (zkCallback-14894-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> 1379020 INFO (zkCallback-14894-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> 1380102 INFO (qtp548684842-23707) [n:127.0.0.1:49969_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=1351
[junit4] 2> 1380113 INFO (qtp68756340-23704) [n:127.0.0.1:49968_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=1358
[junit4] 2> 1380113 DEBUG (OverseerThreadFactory-14888-thread-1-processing-n:127.0.0.1:49968_solr) [n:127.0.0.1:49968_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1380113 INFO (qtp548684842-23709) [n:127.0.0.1:49969_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> 1380194 DEBUG (ScheduledTrigger-14886-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> 1380251 INFO (zkCallback-14872-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> 1380251 INFO (zkCallback-14894-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> 1380251 INFO (zkCallback-14894-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> 1380251 INFO (zkCallback-14894-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> 1380251 INFO (zkCallback-14872-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> 1380252 INFO (qtp548684842-23709) [n:127.0.0.1:49969_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=1857
[junit4] 2> 1380252 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1380255 INFO (qtp548684842-23705) [n:127.0.0.1:49969_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> 1380257 INFO (qtp548684842-23707) [n:127.0.0.1:49969_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1380258 INFO (qtp68756340-23708) [n:127.0.0.1:49968_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> 1380259 INFO (qtp68756340-23710) [n:127.0.0.1:49968_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1380259 DEBUG (qtp548684842-23703) [n:127.0.0.1:49969_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1380260 INFO (qtp548684842-23703) [n:127.0.0.1:49969_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1380261 DEBUG (zkCallback-14872-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1380261 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1380262 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1380262 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
[junit4] 2> 1380262 DEBUG (OverseerAutoScalingTriggerThread-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1380262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f1253a7{/solr,null,AVAILABLE}
[junit4] 2> 1380264 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@560bb66{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50075}
[junit4] 2> 1380264 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.s.Server Started @1331724ms
[junit4] 2> 1380264 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50075}
[junit4] 2> 1380264 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1380264 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1380265 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1380265 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1380265 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1380265 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-27T08:09:27.854483600Z
[junit4] 2> 1380266 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1380268 INFO (zkConnectionManagerCallback-14928-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1380268 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1380269 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1380455 INFO (OverseerCollectionConfigSetProcessor-72112233704849414-127.0.0.1:49968_solr-n_0000000000) [n:127.0.0.1:49968_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1380564 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1380566 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e3e8246[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1380566 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e3e8246[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1380570 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1fc662d2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1380570 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1fc662d2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1380571 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49955/solr
[junit4] 2> 1380572 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1380574 INFO (zkConnectionManagerCallback-14939-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1380574 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1380715 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1380727 INFO (zkConnectionManagerCallback-14941-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1380727 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1380757 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1380761 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50075_solr as DOWN
[junit4] 2> 1380763 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1380763 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50075_solr
[junit4] 2> 1380764 INFO (zkCallback-14872-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1380764 INFO (zkCallback-14907-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1380764 INFO (zkCallback-14894-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1380764 INFO (zkCallback-14940-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1380768 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:50075_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1380768 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [n:127.0.0.1:
[...truncated too long message...]
otName = solr_49969, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@70bc30f1
[junit4] 2> 1430580 INFO (jetty-closer-14950-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1430650 INFO (zkCallback-14872-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1430651 INFO (jetty-closer-14950-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@560bb66{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1430651 INFO (jetty-closer-14950-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f1253a7{/solr,null,UNAVAILABLE}
[junit4] 2> 1430651 INFO (jetty-closer-14950-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1430732 INFO (jetty-closer-14950-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2e73be4c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1430733 INFO (jetty-closer-14950-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@33bcddd7{/solr,null,UNAVAILABLE}
[junit4] 2> 1430733 INFO (jetty-closer-14950-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1430815 INFO (jetty-closer-14950-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72112233704849414-127.0.0.1:49968_solr-n_0000000000) closing
[junit4] 2> 1430816 INFO (jetty-closer-14950-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@adecd87{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1430817 INFO (jetty-closer-14950-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7c766730{/solr,null,UNAVAILABLE}
[junit4] 2> 1430817 INFO (jetty-closer-14950-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1430817 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1430983 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/collections/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> 25 /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> 24 /solr/overseer/queue
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1430986 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:49955
[junit4] 2> 1430986 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49955
[junit4] 2> 1430986 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49955
[junit4] 2> 1432624 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[BABC3D4BA28C313D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=BABC3D4BA28C313D -Dtests.slow=true -Dtests.locale=sbp -Dtests.timezone=Africa/Windhoek -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 56.1s J3 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([BABC3D4BA28C313D:29A77539FC716A09]: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:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:830)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_BABC3D4BA28C313D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1057, maxMBSortInHeap=6.126978238802593, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3e77d9f5), locale=sbp, timezone=Africa/Windhoek
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=6,threads=1,free=147352152,total=423624704
[junit4] 2> NOTE: All tests run in this JVM: [TestMiniSolrCloudClusterSSL, TestTermsQParserPlugin, PreAnalyzedFieldManagedSchemaCloudTest, SolrShardReporterTest, TestCoreAdminApis, HDFSCollectionsAPITest, TestHashQParserPlugin, TestConfigOverlay, TestReplicationHandlerDiskOverFlow, TestClusterProperties, TestAuthenticationFramework, PeerSyncWithLeaderAndIndexFingerprintCachingTest, CloneFieldUpdateProcessorFactoryTest, DeleteLastCustomShardedReplicaTest, JSONWriterTest, IndexSizeTriggerMixedBoundsTest, SchemaVersionSpecificBehaviorTest, TestPointFields, TestSSLRandomization, StatsReloadRaceTest, TriggerIntegrationTest, ConfigSetsAPITest, TestIndexingPerformance, BufferStoreTest, TestFieldSortValues, TestSolrConfigHandlerCloud, TestScoreJoinQPNoScore, ManagedSchemaRoundRobinCloudTest, DocumentBuilderTest, JavabinLoaderTest, TestChildDocTransformerHierarchy, CustomHighlightComponentTest, CoreAdminCreateDiscoverTest, TestSolrDeletionPolicy1, DistribCursorPagingTest, DistributedSpellCheckComponentTest, TestQueryingOnDownCollection, TaggingAttributeTest, BlockJoinFacetRandomTest, TestHdfsUpdateLog, TestDynamicLoadingUrl, MetricsHistoryIntegrationTest, PingRequestHandlerTest, ClusterStateUpdateTest, TestUtilizeNode, RestartWhileUpdatingTest, TestFieldCacheWithThreads, HdfsNNFailoverTest, CloudMLTQParserTest, AsyncCallRequestStatusResponseTest, FieldMutatingUpdateProcessorTest, TestFuzzyAnalyzedSuggestions, MetricsConfigTest, TestReversedWildcardFilterFactory, MoreLikeThisComponentTest, TestLocalFSCloudBackupRestore, TestDeleteCollectionOnDownNodes, LeaderFailoverAfterPartitionTest, DistributedFacetExistsSmallTest, TestExpandComponent, SolrGraphiteReporterTest, BigEndianAscendingWordDeserializerTest, TestLegacyNumericRangeQueryBuilder, DistributedFacetSimpleRefinementLongTailTest, RuleEngineTest, TestSimNodeAddedTrigger, HLLSerializationTest, TestReRankQParserPlugin, SuggestComponentTest, AddBlockUpdateTest, BasicZkTest, TestStressLiveNodes, UpdateParamsTest, TestCursorMarkWithoutUniqueKey, TestManagedResource, CursorPagingTest, DaemonStreamApiTest, TestFieldCache, TestGeoJSONResponseWriter, SolrCloudExampleTest, SolrCoreTest, CdcrOpsAndBoundariesTest, WordBreakSolrSpellCheckerTest, TestClusterStateMutator, RestoreTriggerStateTest, TestCloudPivotFacet, SignificantTermsQParserPluginTest, TestSolr4Spatial, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, NodeMutatorTest, OverriddenZkACLAndCredentialsProvidersTest, RecoveryAfterSoftCommitTest, ShardRoutingTest, TestSizeLimitedDistributedMap, TestSkipOverseerOperations, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, CollectionsAPIAsyncDistributedZkTest, AutoAddReplicasIntegrationTest, NodeAddedTriggerIntegrationTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [567/910 (1!)] on J3 in 56.21s, 1 test, 1 failure <<< FAILURES!
[...truncated 48986 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1180/consoleText
[repro] Revision: 00c277673c75a95bc163bc3753efe3110a430f75
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[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 3486 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=BABC3D4BA28C313D -Dtests.slow=true -Dtests.locale=sbp -Dtests.timezone=Africa/Windhoek -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 83 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]