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/22 16:11:19 UTC
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-15-ea+16) -
Build # 8866 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8866/
Java: 64bit/jdk-15-ea+16 -XX:+UseCompressedOops -XX:+UseSerialGC
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([598C9DD6EEAFF98:968381AF3017A4AC]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 14900 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1402218 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1402220 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\data-dir-108-001
[junit4] 2> 1402220 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1402222 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-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> 1402222 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1402224 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1402225 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001
[junit4] 2> 1402225 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1402227 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1402227 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer start zk server on port: 54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54540
[junit4] 2> 1402328 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402337 INFO (zkConnectionManagerCallback-14674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402337 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402347 INFO (zkConnectionManagerCallback-14676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402348 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402349 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402353 INFO (zkConnectionManagerCallback-14678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402353 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c6e530b{/solr,null,AVAILABLE}
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ca42e47{/solr,null,AVAILABLE}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7f8bbb6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54558}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.Server Started @1346137ms
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54558}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b78d429{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54557}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.Server Started @1346137ms
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54557}
[junit4] 2> 1402468 ERROR (jetty-launcher-14679-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1402468 ERROR (jetty-launcher-14679-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:56.654505900Z
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:56.654505900Z
[junit4] 2> 1402469 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402469 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402472 INFO (zkConnectionManagerCallback-14683-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402472 INFO (zkConnectionManagerCallback-14681-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1402579 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1402581 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5396570c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402581 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5396570c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402584 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28b415ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402584 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28b415ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402585 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1402586 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402589 INFO (zkConnectionManagerCallback-14694-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402589 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402685 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1402686 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a5942fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402686 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a5942fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402690 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5fc63364[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402690 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5fc63364[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402692 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1402694 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402696 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54558_solr
[junit4] 2> 1402697 INFO (zkConnectionManagerCallback-14707-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402697 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402697 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.Overseer Overseer (id=72086613484896261-127.0.0.1:54558_solr-n_0000000000) starting
[junit4] 2> 1402703 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54558_solr
[junit4] 2> 1402703 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:54558_solr
[junit4] 2> 1402704 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1402704 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1402705 INFO (zkCallback-14706-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1402706 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1402706 WARN (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_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> 1402707 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:54557_solr as DOWN
[junit4] 2> 1402708 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1402708 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54557_solr
[junit4] 2> 1402709 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:54558_solr]
[junit4] 2> 1402709 INFO (zkCallback-14693-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1402709 INFO (zkCallback-14706-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1402709 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1402710 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1402710 DEBUG (ScheduledTrigger-14712-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1402712 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1402713 WARN (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_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> 1402736 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1402738 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1402758 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402763 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402766 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402766 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402767 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.
[junit4] 2> 1402795 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1402796 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402798 INFO (zkConnectionManagerCallback-14730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402798 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402799 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1402800 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54540/solr ready
[junit4] 2> 1402806 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1402812 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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> 1402814 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1402816 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_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> 1402817 DEBUG (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1402818 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 1402818 DEBUG (zkCallback-14693-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1402819 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1402820 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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> 1402820 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1402820 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1402820 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1402824 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1402825 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1402827 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402828 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402829 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402831 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402832 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402834 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402835 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402837 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402838 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402839 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402841 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402843 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402844 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402846 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402847 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402849 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402851 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402852 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402853 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402855 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402856 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402858 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402859 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402861 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402863 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402864 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402865 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402867 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402868 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402870 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402872 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402873 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402875 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402876 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402878 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402879 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402881 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402882 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402884 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402885 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1402887 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1402888 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1402990 WARN (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402991 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1402995 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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=3
[junit4] 2> 1402998 INFO (qtp1900829492-23436) [n:127.0.0.1:54558_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=3
[junit4] 2> 1402999 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1403002 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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=3
[junit4] 2> 1403006 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_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> 1403009 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 442802402 sessionWrapper.createTime 442802392138700, this.sessionWrapper.createTime 442802392138700
[junit4] 2> 1403013 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54558/solr",
[junit4] 2> "node_name":"127.0.0.1:54558_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403016 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54557/solr",
[junit4] 2> "node_name":"127.0.0.1:54557_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403018 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54557/solr",
[junit4] 2> "node_name":"127.0.0.1:54557_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403217 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403217 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403217 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403235 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1403235 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1403235 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1403268 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403268 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403268 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403413 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403413 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403414 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403414 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1403505 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403505 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403508 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403508 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403508 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403508 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403511 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403512 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403512 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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 1667405958558515200
[junit4] 2> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403515 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403515 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_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> 1403516 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403516 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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 1667405958560612352
[junit4] 2> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_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> 1403517 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403517 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_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> 1403519 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_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> 1403520 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403520 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403520 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403520 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403522 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 1403522 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403537 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403537 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403541 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=2 timeoute in=9999ms
[junit4] 2> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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 1667405958587875328
[junit4] 2> 1403542 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403542 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_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> 1403547 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1403547 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403547 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_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> 1403687 DEBUG (ScheduledTrigger-14712-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> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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:54557/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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:54557/solr START replicas=[http://127.0.0.1:54558/solr/testScheduledTrigger_shard1_replica_n1/, http://127.0.0.1:54557/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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:54557/solr DONE. We have no versions. sync failed.
[junit4] 2> 1404031 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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> 1404031 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_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> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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/72086613484896262-core_node5-n_0000000000
[junit4] 2> 1404034 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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:54557/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 1404134 INFO (zkCallback-14706-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> 1404135 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404139 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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=946
[junit4] 2> 1404236 INFO (zkCallback-14706-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> 1404236 INFO (zkCallback-14706-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> 1404520 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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=1338
[junit4] 2> 1404529 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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=1347
[junit4] 2> 1404529 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1404530 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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> 1404625 INFO (zkCallback-14706-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> 1404625 INFO (zkCallback-14706-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> 1404625 INFO (zkCallback-14693-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> 1404625 INFO (zkCallback-14693-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> 1404626 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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=1853
[junit4] 2> 1404626 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1404626 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404630 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1404630 INFO (qtp1900829492-23436) [n:127.0.0.1:54558_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> 1404631 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_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> 1404632 DEBUG (qtp1438589166-23441) [n:127.0.0.1:54557_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1404633 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1404633 DEBUG (zkCallback-14693-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1404633 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1404634 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1404634 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1404635 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@73971f88{/solr,null,AVAILABLE}
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@10f76023{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54605}
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.Server Started @1348364ms
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54605}
[junit4] 2> 1404637 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:58.823324700Z
[junit4] 2> 1404638 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1404640 INFO (zkConnectionManagerCallback-14750-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1404640 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1404641 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1404662 DEBUG (ScheduledTrigger-14712-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> 1404745 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1404747 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@686336fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404747 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@686336fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404749 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@62dcd9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404749 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@62dcd9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404750 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1404751 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1404754 INFO (zkConnectionManagerCallback-14761-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1404754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1404760 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1404763 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:54605_solr as DOWN
[junit4] 2> 1404764 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1404764 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54605_solr
[junit4] 2> 1404765 INFO (zkCallback-14693-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14706-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14760-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404767 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1404767 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_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> 1404773 INFO (OverseerCollectionConfigSetProcessor-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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> 1404790 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1404807 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404817 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404818 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404818 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node3\.
[junit4] 2> 1404833 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 1404838 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigg
[...truncated too long message...]
[junit4] 2> 1439582 INFO (jetty-closer-14770-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10f76023{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@73971f88{/solr,null,UNAVAILABLE}
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439712 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5b78d429{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439712 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c6e530b{/solr,null,UNAVAILABLE}
[junit4] 2> 1439713 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439782 INFO (jetty-closer-14770-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72086613484896261-127.0.0.1:54558_solr-n_0000000000) closing
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7f8bbb6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3ca42e47{/solr,null,UNAVAILABLE}
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439784 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1439929 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> 7 /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> 21 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72086613484896262-core_node5-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:54540
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54540
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54540
[junit4] 2> 1441339 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=598C9DD6EEAFF98 -Dtests.slow=true -Dtests.locale=ko-KR -Dtests.timezone=Atlantic/Stanley -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 39.1s J3 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([598C9DD6EEAFF98:968381AF3017A4AC]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=8, maxDocsPerChunk=1, blockShift=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=8, blockSize=3)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5abfe250), locale=ko-KR, timezone=Atlantic/Stanley
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea (64-bit)/cpus=6,threads=1,free=164628552,total=358162432
[junit4] 2> NOTE: All tests run in this JVM: [NoCacheHeaderTest, ClusterStateUpdateTest, TestNestedDocsSort, TestCollationField, DistributedUpdateProcessorTest, TestLRUStatsCacheCloud, TestCursorMarkWithoutUniqueKey, TestDynamicLoading, DistributedExpandComponentTest, ReindexCollectionTest, TestQueryingOnDownCollection, CursorPagingTest, DocValuesNotIndexedTest, BasicZkTest, AtomicUpdateProcessorFactoryTest, HighlighterMaxOffsetTest, ConfigSetsAPITest, SuggesterTest, FuzzySearchTest, BasicAuthStandaloneTest, TestInitParams, OverseerRolesTest, TestNumericTerms64, TestCryptoKeys, DataDrivenBlockJoinTest, SuggestComponentContextFilterQueryTest, XMLAtomicUpdateMultivalueTest, DocValuesMultiTest, TestExternalRoleRuleBasedAuthorizationPlugin, AddReplicaTest, TestSolrCloudWithHadoopAuthPlugin, MetricsHistoryIntegrationTest, SolrCoreCheckLockOnStartupTest, IndexSchemaTest, TestJsonFacetErrors, OutputWriterTest, CoreSorterTest, PathHierarchyTokenizerFactoryTest, LoggingHandlerTest, SimpleFacetsTest, MoveReplicaHDFSTest, JvmMetricsTest, SoftAutoCommitTest, TlogReplayBufferedWhileIndexingTest, ImplicitSnitchTest, HttpPartitionTest, TestGraphTermsQParserPlugin, TestSchemaVersionResource, CdcrBootstrapTest, TestExportTool, TestManagedSynonymGraphFilterFactory, TestCustomDocTransformer, TestJsonRequest, TestCloudPhrasesIdentificationComponent, TestLRUStatsCache, NodeMarkersRegistrationTest, OverseerTest, BinaryUpdateRequestHandlerTest, TestTrieFacet, ShardRoutingCustomTest, TestMaxScoreQueryParser, HdfsChaosMonkeySafeLeaderTest, TestComplexPhraseQParserPlugin, TestSolr4Spatial2, InfixSuggestersTest, AutoscalingHistoryHandlerTest, BlockCacheTest, SplitHandlerTest, TestSortByMinMaxFunction, ZkControllerTest, LeaderFailoverAfterPartitionTest, PrimUtilsTest, TestExportWriter, CollectionsAPIDistributedZkTest, TolerantUpdateProcessorTest, TemplateUpdateProcessorTest, TestTolerantUpdateProcessorRandomCloud, TestCoreContainer, TestSchemalessBufferedUpdates, DistributedFacetPivotSmallTest, TestFieldCacheSortRandom, SearchRateTriggerIntegrationTest, DistribCursorPagingTest, StressHdfsTest, TestReplicationHandlerBackup, TestManagedSchemaThreadSafety, TestAuthenticationFramework, CopyFieldTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestGroupingSearch, TestSimpleTrackingShardHandler, BasicDistributedZk2Test, ChaosMonkeySafeLeaderTest, ClusterStateMockUtilTest, ClusterStateTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DeleteStatusTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, PeerSyncReplicationTest, TestDistributedMap, TestDownShardTolerantSearch, TestDynamicFieldNamesIndexCorrectly, TestRequestForwarding, TestSolrCloudWithDelegationTokens, TestStressLiveNodes, SimpleCollectionCreateDeleteTest, TestRequestStatusCollectionAPI, HttpTriggerListenerTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [572/913 (1!)] on J3 in 39.15s, 1 test, 1 failure <<< FAILURES!
[...truncated 45995 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8866/consoleText
[repro] Revision: 78f4a5b8ff854861ac6ad17c27016e222463e54c
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[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 3158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=598C9DD6EEAFF98 -Dtests.slow=true -Dtests.locale=ko-KR -Dtests.timezone=Atlantic/Stanley -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]