Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8848/
Java: 64bit/jdk-13.0.2 -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([E6698BFE835E43E6:7572C38CDDA318D2]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:830)




Build Log:
[...truncated 14507 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
   [junit4]   2> 997861 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[E6698BFE835E43E6]-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> 997861 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[E6698BFE835E43E6]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\data-dir-68-001
   [junit4]   2> 997865 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[E6698BFE835E43E6]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 997865 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[E6698BFE835E43E6]-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> 997865 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[E6698BFE835E43E6]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 997871 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
   [junit4]   2> 997873 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001
   [junit4]   2> 997874 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 997874 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 997874 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 998021 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer start zk server on port: 54101
   [junit4]   2> 998021 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:54101
   [junit4]   2> 998021 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54101
   [junit4]   2> 998021 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54101
   [junit4]   2> 998025 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998031 INFO  (zkConnectionManagerCallback-8687-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998031 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998036 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998040 INFO  (zkConnectionManagerCallback-8689-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998040 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998043 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998047 INFO  (zkConnectionManagerCallback-8691-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998047 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
   [junit4]   2> 998194 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
   [junit4]   2> 998198 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 998198 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 998198 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 998198 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1d3a854a{/solr,null,AVAILABLE}
   [junit4]   2> 998200 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 998200 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 998200 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 998200 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@17889b25{/solr,null,AVAILABLE}
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2775b914{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?????}
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54116}
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2de93252{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?????}
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.s.Server Started @??????ms
   [junit4]   2> 998202 ERROR (jetty-launcher-8692-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54114}
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 998202 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T16:41:42.820467900Z
   [junit4]   2> 998203 ERROR (jetty-launcher-8692-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T16:41:42.821333600Z
   [junit4]   2> 998203 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998204 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998208 INFO  (zkConnectionManagerCallback-8696-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998208 INFO  (zkConnectionManagerCallback-8694-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998208 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998208 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998210 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 998210 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 998373 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 998374 WARN  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3eb9c40e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998374 WARN  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@3eb9c40e[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998381 WARN  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@79aecec5[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998381 WARN  (jetty-launcher-8692-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@79aecec5[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998383 INFO  (jetty-launcher-8692-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54101/solr
   [junit4]   2> 998385 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 998388 INFO  (zkConnectionManagerCallback-8707-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998388 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 998513 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 998516 WARN  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@459f6357[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998517 WARN  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@459f6357[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998524 WARN  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@1975d543[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998524 WARN  (jetty-launcher-8692-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@1975d543[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 998526 INFO  (jetty-launcher-8692-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54101/solr
   [junit4]   2> 998528 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 998532 INFO  (zkConnectionManagerCallback-8720-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998532 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 998551 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:54116_solr
   [junit4]   2> 998552 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.Overseer Overseer 
(id=72064956756852742-127.0.0.1:54116_solr-n_0000000000) starting
   [junit4]   2> 998563 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54116_solr
   [junit4]   2> 998566 INFO  
(OverseerStateUpdate-72064956756852742-127.0.0.1:54116_solr-n_0000000000) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:54116_solr
   [junit4]   2> 998568 INFO  
(OverseerStateUpdate-72064956756852742-127.0.0.1:54116_solr-n_0000000000) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 998570 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 998571 WARN  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_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> 998573 INFO  (zkCallback-8706-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 998574 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 998578 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with 
znode version 1
   [junit4]   2> 998582 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 0
   [junit4]   2> 998582 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 998583 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:54114_solr as DOWN
   [junit4]   2> 998583 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 1000 transient cores
   [junit4]   2> 998583 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54114_solr
   [junit4]   2> 998591 INFO  (zkCallback-8706-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 998591 INFO  (zkCallback-8719-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 998598 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 998598 WARN  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_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> 998610 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:54114_solr, 127.0.0.1:54116_solr]
   [junit4]   2> 998613 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 998616 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 998621 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 998621 DEBUG (ScheduledTrigger-8725-thread-1) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 2
   [junit4]   2> 998643 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 998662 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54116.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998693 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54116.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998694 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54116.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998697 INFO  (jetty-launcher-8692-thread-1) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node1\.
   [junit4]   2> 998704 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54114.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998712 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54114.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998712 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54114.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 998716 INFO  (jetty-launcher-8692-thread-2) 
[n:127.0.0.1:54114_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node2\.
   [junit4]   2> 998739 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 998742 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 998745 INFO  (zkConnectionManagerCallback-8743-thread-1) [     
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 998746 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 998748 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 998749 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:54101/solr ready
   [junit4]   2> 998763 INFO  (qtp590272583-13302) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 998777 INFO  (qtp590272583-13304) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=3
   [junit4]   2> 998784 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=3
   [junit4]   2> 998785 DEBUG (qtp590272583-13303) [n:127.0.0.1:54114_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 998786 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 998788 INFO  (qtp590272583-13302) [n:127.0.0.1:54114_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> 998790 DEBUG (zkCallback-8719-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 998791 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 998795 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testScheduledTrigger
   [junit4]   2> 998795 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 998797 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 998797 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2> 998801 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
   [junit4]   2> 998804 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998806 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998809 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998810 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998810 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998816 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998817 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998821 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998824 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998826 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998827 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998832 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998837 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998842 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998844 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998844 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998849 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998853 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998855 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998857 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998860 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998864 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998866 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998869 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998872 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998875 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998877 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998880 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998883 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998891 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998894 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998903 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998905 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998907 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998916 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998919 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998922 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998925 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998930 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 998935 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
   [junit4]   2> 998943 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
   [junit4]   2> 998945 INFO  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
   [junit4]   2> 999101 WARN  
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_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> 999102 DEBUG 
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
   [junit4]   2> 999111 INFO  (qtp590272583-13304) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=4
   [junit4]   2> 999115 INFO  (qtp699548242-13309) [n:127.0.0.1:54116_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=3
   [junit4]   2> 999121 DEBUG 
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.s.c.a.PolicyHelper New session created 
   [junit4]   2> 999128 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=3
   [junit4]   2> 999133 INFO  (qtp699548242-13306) [n:127.0.0.1:54116_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=3
   [junit4]   2> 999139 DEBUG 
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 112347071 sessionWrapper.createTime 112347057970900, 
this.sessionWrapper.createTime 112347057970900 
   [junit4]   2> 999145 INFO  
(OverseerStateUpdate-72064956756852742-127.0.0.1:54116_solr-n_0000000000) 
[n:127.0.0.1:54116_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:54116/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:54116_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 999151 INFO  
(OverseerStateUpdate-72064956756852742-127.0.0.1:54116_solr-n_0000000000) 
[n:127.0.0.1:54116_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:54114/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:54114_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 999156 INFO  
(OverseerStateUpdate-72064956756852742-127.0.0.1:54116_solr-n_0000000000) 
[n:127.0.0.1:54116_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:54114/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:54114_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 999442 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 999442 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 999442 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 999442 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr    
x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 
transient cores
   [junit4]   2> 999467 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 999468 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 999468 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 999531 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 999531 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 999531 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 999857 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 999857 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 999857 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_54116.solr.core.testScheduledTrigger.shard1.replica_n1' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 999861 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
   [junit4]   2> 999863 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 999863 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 999863 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_54114.solr.core.testScheduledTrigger.shard1.replica_n4' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 999863 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
   [junit4]   2> 999863 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 999863 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 999863 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_54114.solr.core.testScheduledTrigger.shard1.replica_n2' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 999863 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
   [junit4]   2> 1000005 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000006 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000007 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000007 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000011 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000011 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1000011 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000011 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1000013 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000013 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000014 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000014 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000015 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000015 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000015 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000015 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000015 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000015 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000015 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1000017 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000017 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1000017 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000017 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000017 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1000018 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000018 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000018 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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 1667047206038798336
   [junit4]   2> 1000018 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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 1667047206038798336
   [junit4]   2> 1000020 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000020 INFO  
(searcherExecutor-8745-thread-1-processing-n:127.0.0.1:54116_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:54116_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1000020 INFO  
(searcherExecutor-8745-thread-1-processing-n:127.0.0.1:54116_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:54116_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> 1000020 INFO  
(searcherExecutor-8749-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:54114_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1000020 INFO  
(searcherExecutor-8749-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:54114_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> 1000020 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000020 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000020 INFO  
(searcherExecutor-8749-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:54114_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> 1000020 INFO  
(searcherExecutor-8745-thread-1-processing-n:127.0.0.1:54116_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:54116_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> 1000024 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000025 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1000025 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000027 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000028 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/testScheduledTrigger/terms/shard1 to 
Terms{values={core_node3=0}, version=0}
   [junit4]   2> 1000028 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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 1667047206049284096
   [junit4]   2> 1000028 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Failed to save 
terms, version is not a match, retrying
   [junit4]   2> 1000028 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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> 1000029 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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_node3=0, core_node5=0}, version=1}
   [junit4]   2> 1000029 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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> 1000029 INFO  
(searcherExecutor-8747-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:54114_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1000029 INFO  
(searcherExecutor-8747-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:54114_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> 1000031 INFO  
(searcherExecutor-8747-thread-1-processing-n:127.0.0.1:54114_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:54114_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> 1000031 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000031 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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> 1000035 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1000035 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1000035 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas 
to http://127.0.0.1:54116/solr/testScheduledTrigger_shard1_replica_n1/
   [junit4]   2> 1000036 DEBUG (ScheduledTrigger-8725-thread-1) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 2
   [junit4]   2> 1000036 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: 
core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:54116/solr  
START 
replicas=[http://127.0.0.1:54114/solr/testScheduledTrigger_shard1_replica_n2/, 
http://127.0.0.1:54114/solr/testScheduledTrigger_shard1_replica_n4/] 
nUpdates=100
   [junit4]   2> 1000037 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: 
core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:54116/solr  
DONE. We have no versions. sync failed.
   [junit4]   2> 1000039 INFO  (qtp590272583-13304) [n:127.0.0.1:54114_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request 
[testScheduledTrigger_shard1_replica_n2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1000039 INFO  (qtp590272583-13371) [n:127.0.0.1:54114_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> 1000040 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's 
attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1000040 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext 
We failed sync, but we have no versions - we can't sync in that case - we were 
active before, so become leader anyway
   [junit4]   2> 1000040 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/testScheduledTrigger/leaders/shard1/leader after winning as 
/collections/testScheduledTrigger/leader_elect/shard1/election/72064956756852742-core_node3-n_0000000000
   [junit4]   2> 1000042 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:54116/solr/testScheduledTrigger_shard1_replica_n1/ shard1
   [junit4]   2> 1000188 INFO  (zkCallback-8719-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> 1000188 INFO  (zkCallback-8719-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> 1000191 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 1000197 INFO  (qtp699548242-13308) [n:127.0.0.1:54116_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=540
   [junit4]   2> 1000335 INFO  (zkCallback-8719-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> 1000335 INFO  (zkCallback-8719-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> 1000337 INFO  (zkCallback-8719-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testScheduledTrigger/state.json] for collection 
[testScheduledTrigger] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1001451 DEBUG (ScheduledTrigger-8725-thread-1) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 2
   [junit4]   2> 1001464 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_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=1445
   [junit4]   2> 1001469 INFO  (qtp590272583-13300) [n:127.0.0.1:54114_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=1449
   [junit4]   2> 1001471 DEBUG 
(OverseerThreadFactory-8727-thread-1-processing-n:127.0.0.1:54116_solr) 
[n:127.0.0.1:54116_solr     ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
   [junit4]   2> 1001474 INFO  (qtp590272583-13302) [n:127.0.0.1:54114_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> 1001597 INFO  
(OverseerCollectionConfigSetProcessor-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [n:127.0.0.1:54116_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> 1001603 INFO  (zkCallback-8706-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> 1001603 INFO  (zkCallback-8719-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> 1001603 INFO  (zkCallback-8719-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testScheduledTrigger/state.json] for collection 
[testScheduledTrigger] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1001603 INFO  (zkCallback-8706-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> 1001603 INFO  (zkCallback-8719-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> 1001603 INFO  (zkCallback-8706-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testScheduledTrigger/state.json] for collection 
[testScheduledTrigger] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1001603 INFO  (qtp590272583-13302) [n:127.0.0.1:54114_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=2014
   [junit4]   2> 1001606 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testScheduledTrigger
   [junit4]   2> 1001608 INFO  (qtp590272583-13304) [n:127.0.0.1:54114_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> 1001615 INFO  (qtp590272583-13303) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 1001619 INFO  (qtp699548242-13293) [n:127.0.0.1:54116_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> 1001619 INFO  (qtp699548242-13309) [n:127.0.0.1:54116_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 1001625 DEBUG (qtp590272583-13371) [n:127.0.0.1:54114_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1001625 INFO  (qtp590272583-13371) [n:127.0.0.1:54114_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
2 ...
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 
2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 
13.0.2+8
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1001630 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@54aeb760{/solr,null,AVAILABLE}
   [junit4]   2> 1001634 DEBUG (zkCallback-8719-thread-2) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1001634 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.AbstractConnector Started ServerConnector@41f79e5c{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:?????}
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.s.Server Started @??????ms
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54241}
   [junit4]   2> 1001634 ERROR 
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 9.0.0
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 1001634 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T16:41:46.252961400Z
   [junit4]   2> 1001641 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1001641 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1001641 DEBUG 
(OverseerAutoScalingTriggerThread-72064956756852742-127.0.0.1:54116_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 1001646 INFO  (zkConnectionManagerCallback-8763-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1001646 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1001646 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1001835 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1001840 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@490c1766[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1001840 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@490c1766[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1001844 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@773478c4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1001844 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@773478c4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1001844 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54101/solr
   [junit4]   2> 1001851 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 1001851 INFO  (zkConnectionManagerCallback-8774-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1001851 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1001863 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1001867 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:54241_solr as DOWN
   [junit4]   2> 1001872 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 1000 transient cores
   [junit4]   2> 1001872 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54241_solr
   [junit4]   2> 1001872 INFO  (zkCallback-8742-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1001872 INFO  (zkCallback-8719-thread-2) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1001872 INFO  (zkCallback-8706-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1001872 INFO  (zkCallback-8773-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1001878 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1001878 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_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> 1001910 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1001943 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54241.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 1001954 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54241.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2b490092
   [junit4]   2> 1001954 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [n:127.0.0.1:54241_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitorin

[...truncated too long message...]

ser-8783-thread-2) [     ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.node tag=null
   [junit4]   2> 1051311 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2514a0f: rootName = 
solr_54114, domain = solr.node, service url = null, agent id = null] for 
registry solr.node/com.codahale.metrics.MetricRegistry@29ecb068
   [junit4]   2> 1051324 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm 
tag=null
   [junit4]   2> 1051324 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@267e4f31: rootName = 
solr_54114, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm/com.codahale.metrics.MetricRegistry@40f7c784
   [junit4]   2> 1051325 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 1051325 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@109b82b0: rootName = 
solr_54114, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty/com.codahale.metrics.MetricRegistry@524a132e
   [junit4]   2> 1051325 INFO  (jetty-closer-8783-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster 
tag=null
   [junit4]   2> 1051380 INFO  (jetty-closer-8783-thread-3) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@41f79e5c{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1051381 INFO  (jetty-closer-8783-thread-3) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@54aeb760{/solr,null,UNAVAILABLE}
   [junit4]   2> 1051381 INFO  (jetty-closer-8783-thread-3) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1051392 INFO  (jetty-closer-8783-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72064956756852742-127.0.0.1:54116_solr-n_0000000000) closing
   [junit4]   2> 1051393 INFO  (zkCallback-8706-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1051395 INFO  (jetty-closer-8783-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2775b914{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1051396 INFO  (jetty-closer-8783-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@17889b25{/solr,null,UNAVAILABLE}
   [junit4]   2> 1051397 INFO  (jetty-closer-8783-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1051482 INFO  (jetty-closer-8783-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2de93252{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1051482 INFO  (jetty-closer-8783-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1d3a854a{/solr,null,UNAVAILABLE}
   [junit4]   2> 1051482 INFO  (jetty-closer-8783-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1051484 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1051653 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        8       
/solr/collections/testScheduledTrigger/terms/shard1
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        3       
/solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2>        3       /solr/packages.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        2       /solr/configs/testScheduledTrigger.AUTOCREATED
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        29      
/solr/collections/testScheduledTrigger/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        4       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        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> 1051656 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:54101
   [junit4]   2> 1051656 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54101
   [junit4]   2> 1051656 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54101
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger 
-Dtests.seed=E6698BFE835E43E6 -Dtests.slow=true -Dtests.locale=ar-001 
-Dtests.timezone=Africa/Tripoli -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 55.5s J1 | 
ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ScheduledTrigger did 
not fire in time
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E6698BFE835E43E6:7572C38CDDA318D2]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:567)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:830)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_E6698BFE835E43E6-001
   [junit4]   2> 1053314 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[E6698BFE835E43E6])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): {}, 
docValues:{}, maxPointsInLeafNode=1384, maxMBSortInHeap=7.123631666446, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4c7e9333),
 locale=ar-001, timezone=Africa/Tripoli
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 13.0.2 
(64-bit)/cpus=6,threads=1,free=141863904,total=327806976
   [junit4]   2> NOTE: All tests run in this JVM: 
[VMParamsZkACLAndCredentialsProvidersTest, TestFaceting, TestMultiWordSynonyms, 
TestSolrIndexConfig, CollectionsAPISolrJTest, 
DistributedFacetPivotLongTailTest, TestPullReplica, TestLegacyNumericUtils, 
TestSweetSpotSimilarityFactory, BasicFunctionalityTest, 
BasicDistributedZk2Test, TestNumericTerms32, ZkShardTermsTest, 
ShowFileRequestHandlerTest, ExecutePlanActionTest, DateMathParserTest, 
TestLMJelinekMercerSimilarityFactory, TestDocSet, TestWithCollection, 
TestNumericTokenStream, CoreAdminOperationTest, TestPhraseSuggestions, 
TestCloudNestedDocsSort, TestOrdValues, WrapperMergePolicyFactoryTest, 
PeerSyncTest, TestCollectionAPI, SSLMigrationTest, ChaosMonkeySafeLeaderTest, 
TestLuceneIndexBackCompat, TestStressThreadBackup, 
TestManagedStopFilterFactory, PrimitiveFieldTypeTest, SuggesterTSTTest, 
TestDistributedSearch, TestDeprecatedFilters, TestTolerantSearch, 
NumericFieldsTest, DistributedTermsComponentTest, IndexSizeEstimatorTest, 
TestUpdate, TestFieldCacheSort, SpellPossibilityIteratorTest, TestBadConfig, 
LeaderElectionIntegrationTest, TestSimPolicyCloud, 
IgnoreLargeDocumentProcessorFactoryTest, TestBinaryResponseWriter, 
OrderedExecutorTest, TestCustomStream, DistributedQueueTest, 
TestRandomDVFaceting, TestJsonFacetsWithNestedObjects, TestApiFramework, 
DateRangeFieldTest, TestStressLucene, TestShardHandlerFactory, 
CdcrUpdateLogTest, TestInitQParser, ClusterStateMockUtilTest, 
TestFieldCollectionResource, SolrPluginUtilsTest, SolrMetricManagerTest, 
EchoParamsTest, MissingSegmentRecoveryTest, TestExactStatsCache, 
CachingDirectoryFactoryTest, TestRetrieveFieldsOptimizer, 
StatelessScriptUpdateProcessorFactoryTest, ExternalFileFieldSortTest, 
TestConfigSetsAPIExclusivity, ScheduledTriggerIntegrationTest]
   [junit4] Completed [457/912 (1!)] on J1 in 55.48s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 53976 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8848/consoleText

[repro] Revision: 10d1ecb431c3bd0a0c8995d65db14a1a5bc85094

[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 3156 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=E6698BFE835E43E6 
-Dtests.slow=true -Dtests.locale=ar-001 -Dtests.timezone=Africa/Tripoli 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1

[...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...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to