Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1136/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UseParallelGC

1 tests failed.
FAILED:  
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger

Error Message:
ScheduledTrigger did not fire in time

Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
        at 
__randomizedtesting.SeedInfo.seed([64851057649BAC3E:F79E58253A66F70A]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:832)




Build Log:
[...truncated 15483 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
   [junit4]   2> 1575942 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
   [junit4]   2> 1575942 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1575943 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\data-dir-100-001
   [junit4]   2> 1575943 WARN  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
   [junit4]   2> 1575943 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1575944 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[64851057649BAC3E]-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> 1575945 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
   [junit4]   2> 1575946 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001
   [junit4]   2> 1575946 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1575946 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1575946 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1576042 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer start zk server on port: 62632
   [junit4]   2> 1576042 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:62632
   [junit4]   2> 1576042 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62632
   [junit4]   2> 1576042 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62632
   [junit4]   2> 1576043 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576047 INFO  (zkConnectionManagerCallback-12604-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576047 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576049 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576050 INFO  (zkConnectionManagerCallback-12606-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576050 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576050 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576052 INFO  (zkConnectionManagerCallback-12608-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576052 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576152 WARN  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1576152 WARN  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@819a91{/solr,null,AVAILABLE}
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7e21a52a{/solr,null,AVAILABLE}
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@4f31cc0{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?????}
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.s.Server Started @???????ms
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62646}
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@1a1808cb{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?????}
   [junit4]   2> 1576152 ERROR (jetty-launcher-12609-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.s.Server Started @???????ms
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.6.0
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62645}
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-17T10:56:04.101717400Z
   [junit4]   2> 1576152 ERROR (jetty-launcher-12609-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.6.0
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1576152 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-17T10:56:04.101717400Z
   [junit4]   2> 1576155 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576155 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576156 INFO  (zkConnectionManagerCallback-12613-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576156 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576156 INFO  (zkConnectionManagerCallback-12611-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576156 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576156 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1576156 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1576310 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1576311 WARN  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@122eeedd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576311 WARN  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@122eeedd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576314 WARN  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@674f715f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576314 WARN  (jetty-launcher-12609-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@674f715f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576314 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62632/solr
   [junit4]   2> 1576315 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576316 INFO  (zkConnectionManagerCallback-12624-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576316 INFO  (jetty-launcher-12609-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576431 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1576432 WARN  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@699fde21[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576432 WARN  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@699fde21[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576434 WARN  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@26bcaa1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576434 WARN  (jetty-launcher-12609-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@26bcaa1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1576435 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62632/solr
   [junit4]   2> 1576436 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576438 INFO  (zkConnectionManagerCallback-12635-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576438 INFO  (jetty-launcher-12609-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576451 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1576453 INFO  (zkConnectionManagerCallback-12637-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576453 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1576524 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:62646_solr
   [junit4]   2> 1576525 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.Overseer Overseer 
(id=72058175064047623-127.0.0.1:62646_solr-n_0000000000) starting
   [junit4]   2> 1576528 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62646_solr
   [junit4]   2> 1576528 INFO  
(OverseerStateUpdate-72058175064047623-127.0.0.1:62646_solr-n_0000000000) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:62646_solr
   [junit4]   2> 1576528 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 1576528 INFO  
(OverseerStateUpdate-72058175064047623-127.0.0.1:62646_solr-n_0000000000) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1576528 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with 
znode version 1
   [junit4]   2> 1576530 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 0
   [junit4]   2> 1576530 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 1576530 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1576530 WARN  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_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> 1576531 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:62646_solr]
   [junit4]   2> 1576531 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1576531 DEBUG (ScheduledTrigger-12642-thread-1) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 1 and last live nodes: 1
   [junit4]   2> 1576531 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 1576546 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1576550 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1576550 INFO  (zkConnectionManagerCallback-12650-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576550 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1576553 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1576557 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:62645_solr as DOWN
   [junit4]   2> 1576557 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1576557 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62645_solr
   [junit4]   2> 1576557 INFO  (zkCallback-12636-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1576557 INFO  (zkCallback-12649-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1576557 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1576561 WARN  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_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> 1576564 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62646.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576569 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62646.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576569 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62646.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576572 INFO  (jetty-launcher-12609-thread-2) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node2\.
   [junit4]   2> 1576578 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1576593 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62645.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576598 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62645.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576598 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_62645.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1576599 INFO  (jetty-launcher-12609-thread-1) 
[n:127.0.0.1:62645_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node1\.
   [junit4]   2> 1576610 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1576611 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1576613 INFO  (zkConnectionManagerCallback-12664-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1576613 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1576613 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1576614 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:62632/solr ready
   [junit4]   2> 1576618 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1576622 INFO  (qtp350221344-19639) [n:127.0.0.1:62645_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=2
   [junit4]   2> 1576624 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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=1
   [junit4]   2> 1576625 DEBUG (qtp350221344-19643) [n:127.0.0.1:62645_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1576625 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 1576625 DEBUG (zkCallback-12636-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 1576627 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 1576627 INFO  (qtp350221344-19645) [n:127.0.0.1:62645_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> 1576627 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1576627 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 1576627 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testScheduledTrigger
   [junit4]   2> 1576636 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2> 1576638 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
   [junit4]   2> 1576640 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576641 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576643 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576644 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576645 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576646 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576647 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576648 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576650 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576651 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576652 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576653 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576654 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576655 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576657 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576658 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576659 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576660 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576661 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576662 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576664 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576664 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576664 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576664 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576668 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576669 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576670 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576671 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576672 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576673 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576674 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576675 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576676 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576677 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576678 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576680 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576681 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576682 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576683 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576684 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1576685 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
   [junit4]   2> 1576686 INFO  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
   [junit4]   2> 1576785 WARN  
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_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> 1576785 DEBUG 
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
   [junit4]   2> 1576787 INFO  (qtp350221344-19639) [n:127.0.0.1:62645_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=2
   [junit4]   2> 1576791 INFO  (qtp171238390-19644) [n:127.0.0.1:62646_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=1
   [junit4]   2> 1576791 DEBUG 
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.s.c.a.PolicyHelper New session created 
   [junit4]   2> 1576793 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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=2
   [junit4]   2> 1576796 INFO  (qtp171238390-19638) [n:127.0.0.1:62646_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=2
   [junit4]   2> 1576798 DEBUG 
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 8866643 sessionWrapper.createTime 8866636381500, 
this.sessionWrapper.createTime 8866636381500 
   [junit4]   2> 1576802 INFO  
(OverseerStateUpdate-72058175064047623-127.0.0.1:62646_solr-n_0000000000) 
[n:127.0.0.1:62646_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:62646/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:62646_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1576808 INFO  
(OverseerStateUpdate-72058175064047623-127.0.0.1:62646_solr-n_0000000000) 
[n:127.0.0.1:62646_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:62645/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:62645_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1576815 INFO  
(OverseerStateUpdate-72058175064047623-127.0.0.1:62646_solr-n_0000000000) 
[n:127.0.0.1:62646_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:62645/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:62645_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1577004 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577004 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577004 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr    
x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1577004 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577017 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.6.0
   [junit4]   2> 1577017 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.6.0
   [junit4]   2> 1577017 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.6.0
   [junit4]   2> 1577029 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1577029 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1577032 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1577131 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577135 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577135 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577166 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577166 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_62646.solr.core.testScheduledTrigger.shard1.replica_n1' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1577166 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
   [junit4]   2> 1577173 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577174 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_62645.solr.core.testScheduledTrigger.shard1.replica_n4' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1577174 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577174 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
   [junit4]   2> 1577174 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_62645.solr.core.testScheduledTrigger.shard1.replica_n2' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@55f245b4
   [junit4]   2> 1577174 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
   [junit4]   2> 1577226 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577226 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577228 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577228 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577230 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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 1666934862175535104
   [junit4]   2> 1577233 INFO  
(searcherExecutor-12666-thread-1-processing-n:127.0.0.1:62646_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:62646_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1577233 INFO  
(searcherExecutor-12666-thread-1-processing-n:127.0.0.1:62646_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:62646_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> 1577234 INFO  
(searcherExecutor-12666-thread-1-processing-n:127.0.0.1:62646_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:62646_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> 1577235 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577235 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577237 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577237 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext 
Waiting until we see more replicas up for shard shard1: total=3 found=1 
timeoute in=9999ms
   [junit4]   2> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1577240 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1577240 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577248 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577248 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1577248 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577248 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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 1666934862194409472
   [junit4]   2> 1577250 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577250 INFO  
(searcherExecutor-12670-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:62645_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1577250 INFO  
(searcherExecutor-12670-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:62645_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> 1577250 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577250 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577251 INFO  
(searcherExecutor-12670-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:62645_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> 1577251 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577251 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1577251 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577251 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577252 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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 1666934862198603776
   [junit4]   2> 1577254 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577254 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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> 1577254 INFO  
(searcherExecutor-12668-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:62645_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1577254 INFO  
(searcherExecutor-12668-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:62645_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> 1577255 INFO  
(searcherExecutor-12668-thread-1-processing-n:127.0.0.1:62645_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:62645_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> 1577255 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577255 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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> 1577489 DEBUG (ScheduledTrigger-12642-thread-2) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 1
   [junit4]   2> 1577718 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577718 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577718 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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:62646/solr/testScheduledTrigger_shard1_replica_n1/
   [junit4]   2> 1577718 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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:62646/solr  
START 
replicas=[http://127.0.0.1:62645/solr/testScheduledTrigger_shard1_replica_n2/, 
http://127.0.0.1:62645/solr/testScheduledTrigger_shard1_replica_n4/] 
nUpdates=100
   [junit4]   2> 1577719 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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:62646/solr  
DONE. We have no versions. sync failed.
   [junit4]   2> 1577719 INFO  (qtp350221344-19713) [n:127.0.0.1:62645_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> 1577719 INFO  (qtp350221344-19639) [n:127.0.0.1:62645_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> 1577719 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577719 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577721 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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/72058175064047623-core_node3-n_0000000000
   [junit4]   2> 1577722 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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:62646/solr/testScheduledTrigger_shard1_replica_n1/ shard1
   [junit4]   2> 1577819 INFO  (zkCallback-12636-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> 1577820 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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> 1577824 INFO  (qtp171238390-19640) [n:127.0.0.1:62646_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=857
   [junit4]   2> 1577918 INFO  (zkCallback-12636-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> 1577918 INFO  (zkCallback-12636-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> 1578216 INFO  (qtp350221344-19641) [n:127.0.0.1:62645_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=1268
   [junit4]   2> 1578216 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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=1267
   [junit4]   2> 1578216 DEBUG 
(OverseerThreadFactory-12644-thread-1-processing-n:127.0.0.1:62646_solr) 
[n:127.0.0.1:62646_solr     ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
   [junit4]   2> 1578217 INFO  (qtp350221344-19645) [n:127.0.0.1:62645_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> 1578310 INFO  (zkCallback-12649-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> 1578310 INFO  (zkCallback-12636-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> 1578310 INFO  (zkCallback-12636-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> 1578310 INFO  (zkCallback-12649-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> 1578311 INFO  (zkCallback-12649-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> 1578311 INFO  (qtp350221344-19645) [n:127.0.0.1:62645_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=1763
   [junit4]   2> 1578311 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testScheduledTrigger
   [junit4]   2> 1578313 INFO  (qtp350221344-19639) [n:127.0.0.1:62645_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> 1578313 INFO  (qtp350221344-19643) [n:127.0.0.1:62645_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=1
   [junit4]   2> 1578315 INFO  (qtp171238390-19642) [n:127.0.0.1:62646_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> 1578316 INFO  (qtp171238390-19644) [n:127.0.0.1:62646_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=1
   [junit4]   2> 1578316 DEBUG (qtp350221344-19713) [n:127.0.0.1:62645_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1578317 INFO  (qtp350221344-19713) [n:127.0.0.1:62645_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 1578317 DEBUG (zkCallback-12636-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1578317 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 1578317 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short 
content, break even is size 23
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
2 ...
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 
2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 
14+36
   [junit4]   2> 1578317 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1578317 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1578318 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@26049676{/solr,null,AVAILABLE}
   [junit4]   2> 1578318 DEBUG 
(OverseerAutoScalingTriggerThread-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.AbstractConnector Started ServerConnector@20179710{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:?????}
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.s.Server Started @???????ms
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62735}
   [junit4]   2> 1578319 ERROR 
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 8.6.0
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1578319 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-17T10:56:06.268945900Z
   [junit4]   2> 1578320 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1578321 INFO  (zkConnectionManagerCallback-12684-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1578321 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1578322 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1578445 DEBUG (ScheduledTrigger-12642-thread-2) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 2
   [junit4]   2> 1578474 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1578475 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@461a5012[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1578475 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@461a5012[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1578477 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4b9e3519[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1578477 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@4b9e3519[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1578478 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62632/solr
   [junit4]   2> 1578479 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1578479 INFO  (zkConnectionManagerCallback-12695-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1578479 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1578541 INFO  
(OverseerCollectionConfigSetProcessor-72058175064047623-127.0.0.1:62646_solr-n_0000000000)
 [n:127.0.0.1:62646_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> 1578578 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 1578580 INFO  (zkConnectionManagerCallback-12697-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1578580 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1578583 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1578586 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:62735_solr as DOWN
   [junit4]   2> 1578591 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1578591 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62735_solr
   [junit4]   2> 1578592 INFO  (zkCallback-12663-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1578592 INFO  (zkCallback-12636-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1578592 INFO  (zkCallback-12649-thread-3) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1578592 INFO  (zkCallback-12696-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1578593 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [n:127.0.0.1:62735_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1578594 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTr

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

4
   [junit4]   2> 1633276 INFO  (jetty-closer-12706-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty 
tag=null
   [junit4]   2> 1633276 INFO  (jetty-closer-12706-thread-2) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@da0940a: rootName = 
solr_62645, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty/com.codahale.metrics.MetricRegistry@7ce1c3dd
   [junit4]   2> 1633276 INFO  (jetty-closer-12706-thread-2) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster 
tag=null
   [junit4]   2> 1633376 INFO  (jetty-closer-12706-thread-3) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@20179710{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1633376 INFO  (jetty-closer-12706-thread-3) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@26049676{/solr,null,UNAVAILABLE}
   [junit4]   2> 1633376 INFO  (jetty-closer-12706-thread-3) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1633395 INFO  (jetty-closer-12706-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72058175064047623-127.0.0.1:62646_solr-n_0000000000) closing
   [junit4]   2> 1633395 INFO  (zkCallback-12649-thread-3) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1633397 INFO  (jetty-closer-12706-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@4f31cc0{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1633397 INFO  (jetty-closer-12706-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7e21a52a{/solr,null,UNAVAILABLE}
   [junit4]   2> 1633397 INFO  (jetty-closer-12706-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1633525 INFO  (jetty-closer-12706-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@1a1808cb{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:?}
   [junit4]   2> 1633525 INFO  (jetty-closer-12706-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@819a91{/solr,null,UNAVAILABLE}
   [junit4]   2> 1633525 INFO  (jetty-closer-12706-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1633526 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1633782 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       
/solr/collections/testScheduledTrigger/terms/shard1
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        3       
/solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2>        3       /solr/packages.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        2       /solr/configs/testScheduledTrigger.AUTOCREATED
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        24      
/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>        14      /solr/live_nodes
   [junit4]   2>        7       /solr/collections
   [junit4]   2>        5       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 1633786 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:62632
   [junit4]   2> 1633786 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62632
   [junit4]   2> 1633786 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62632
   [junit4]   2> 1636312 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[64851057649BAC3E])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger 
-Dtests.seed=64851057649BAC3E -Dtests.slow=true -Dtests.locale=ckb 
-Dtests.timezone=America/North_Dakota/Center -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 60.4s J1 | 
ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ScheduledTrigger did 
not fire in time
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([64851057649BAC3E:F79E58253A66F70A]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_64851057649BAC3E-001
   [junit4]   2> NOTE: test params are: codec=Lucene84, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@68dce11d),
 locale=ckb, timezone=America/North_Dakota/Center
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 
(64-bit)/cpus=6,threads=1,free=190641008,total=488112128
   [junit4]   2> NOTE: All tests run in this JVM: [TestZkChroot, 
TestMacroExpander, SolrTestCaseJ4Test, ZkSolrClientTest, 
AuditLoggerIntegrationTest, TestStressCloudBlindAtomicUpdates, 
TestSimComputePlanAction, ConnectionReuseTest, SolrLogPostToolTest, 
TestRandomDVFaceting, TestDistributedTracing, SSLMigrationTest, 
TestSurroundQueryParser, DocumentBuilderTest, CdcrVersionReplicationTest, 
TestXmlQParserPlugin, MissingSegmentRecoveryTest, TestCloudRecovery2, 
SyncSliceTest, TestFuzzyAnalyzedSuggestions, TestManagedSynonymFilterFactory, 
BlockJoinFacetRandomTest, TestCollectionsAPIViaSolrCloudCluster, 
OverseerCollectionConfigSetProcessorTest, TermsComponentTest, 
WordBreakSolrSpellCheckerTest, TestNonDefinedSimilarityFactory, 
TestBlobHandler, TestTolerantUpdateProcessorCloud, TestDistributedSearch, 
RequiredFieldsTest, TestInfoStreamLogging, RankQueryTest, 
BigEndianAscendingWordDeserializerTest, BasicDistributedZk2Test, 
ChaosMonkeySafeLeaderTest, DistributedQueryComponentOptimizationTest, 
TestSubQueryTransformerCrossCore, ConfigureRecoveryStrategyTest, 
CheckHdfsIndexTest, TestSimGenericDistributedQueue, TestAddFieldRealTimeGet, 
TestScoreJoinQPNoScore, HLLSerializationTest, TestCSVResponseWriter, 
TestLogWatcher, HdfsRestartWhileUpdatingTest, TestComponentsName, 
FieldMutatingUpdateProcessorTest, AutoAddReplicasIntegrationTest, 
TriggerCooldownIntegrationTest, TestFastLRUCache, BufferStoreTest, 
OverseerStatusTest, DeleteShardTest, TestDirectoryFactory, 
MultiThreadedOCPTest, TestAnalyzeInfixSuggestions, PluginInfoTest, 
TestIndexingPerformance, TestPHPSerializedResponseWriter, 
TestTrackingShardHandlerFactory, MetricsHistoryHandlerTest, 
TestLuceneIndexBackCompat, TestCollationField, SpellCheckCollatorTest, 
TestDefaultStatsCache, SOLR749Test, TestReload, JWTAuthPluginIntegrationTest, 
MetricTriggerIntegrationTest, TestGraphTermsQParserPlugin, TestPullReplica, 
TestNRTOpen, ChangedSchemaMergeTest, TestIntervalFaceting, ReplaceNodeTest, 
NodeLostTriggerTest, AlternateDirectoryTest, UUIDUpdateProcessorFallbackTest, 
ParsingFieldUpdateProcessorsTest, IndexSchemaRuntimeFieldTest, 
ZkStateWriterTest, BadComponentTest, TestFieldCacheWithThreads, 
FullSolrCloudDistribCmdsTest, SignatureUpdateProcessorFactoryTest, 
SolrSlf4jReporterTest, TestPseudoReturnFields, UpdateParamsTest, 
DeleteNodeTest, DistributedVersionInfoTest, LeaderFailureAfterFreshStartTest, 
RecoveryZkTest, TestCloudDeleteByQuery, 
TestCloudPhrasesIdentificationComponent, TestConfigSetsAPIZkFailure, 
TestCryptoKeys, TestDistributedMap, TestDownShardTolerantSearch, 
TestLeaderElectionWithEmptyReplica, TestSkipOverseerOperations, 
ZkControllerTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, 
ExecutePlanActionTest, ScheduledTriggerIntegrationTest]
   [junit4] Completed [593/908 (1!)] on J1 in 60.69s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 45921 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1136/consoleText

[repro] Revision: 62847cced885fd8b2402b9adc588247340d16f41

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       ScheduledTriggerIntegrationTest
[repro] ant compile-test

[...truncated 3484 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=64851057649BAC3E -Dtests.slow=true -Dtests.locale=ckb 
-Dtests.timezone=America/North_Dakota/Center -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 86 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