Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1142/
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([C2206F58CF9E46C5:513B272A91631DF1]: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 15416 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
   [junit4]   2> 1449638 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[C2206F58CF9E46C5]-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> 1449639 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[C2206F58CF9E46C5]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\data-dir-144-001
   [junit4]   2> 1449640 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[C2206F58CF9E46C5]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1449641 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[C2206F58CF9E46C5]-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> 1449641 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[C2206F58CF9E46C5]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1449642 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
   [junit4]   2> 1449643 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] 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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001
   [junit4]   2> 1449643 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1449643 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1449643 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1449733 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer start zk server on port: 60659
   [junit4]   2> 1449733 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:60659
   [junit4]   2> 1449733 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60659
   [junit4]   2> 1449733 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60659
   [junit4]   2> 1449735 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1449737 INFO  (zkConnectionManagerCallback-14000-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1449737 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1449739 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1449741 INFO  (zkConnectionManagerCallback-14002-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1449741 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1449741 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1449743 INFO  (zkConnectionManagerCallback-14004-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1449743 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1449835 WARN  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1449835 WARN  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1449835 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1449835 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1449835 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1449835 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1449835 INFO  (jetty-launcher-14005-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> 1449835 INFO  (jetty-launcher-14005-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> 1449836 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@636725f0{/solr,null,AVAILABLE}
   [junit4]   2> 1449836 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@e5f0502{/solr,null,AVAILABLE}
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@7b9edf57{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:60674}
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.s.Server Started @1404013ms
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=60674}
   [junit4]   2> 1449838 ERROR (jetty-launcher-14005-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.6.0
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T18:03:31.547057400Z
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2fe8576{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:60673}
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.s.Server Started @1404014ms
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=60673}
   [junit4]   2> 1449838 ERROR (jetty-launcher-14005-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.6.0
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T18:03:31.547501500Z
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1449838 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1449840 INFO  (zkConnectionManagerCallback-14007-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1449840 INFO  (zkConnectionManagerCallback-14009-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1449840 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1449840 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1449840 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1449840 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1450001 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1450002 WARN  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6272bd51[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450002 WARN  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@6272bd51[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450004 WARN  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2af6027c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450004 WARN  (jetty-launcher-14005-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2af6027c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450005 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60659/solr
   [junit4]   2> 1450006 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1450008 INFO  (zkConnectionManagerCallback-14020-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1450008 INFO  (jetty-launcher-14005-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1450084 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1450085 WARN  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@1433af13[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450085 WARN  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@1433af13[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450087 WARN  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@56872943[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450087 WARN  (jetty-launcher-14005-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@56872943[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1450088 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60659/solr
   [junit4]   2> 1450088 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1450091 INFO  (zkConnectionManagerCallback-14031-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1450091 INFO  (jetty-launcher-14005-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1450116 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1450117 INFO  (zkConnectionManagerCallback-14033-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1450117 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1450177 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:60673_solr
   [junit4]   2> 1450178 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.Overseer Overseer 
(id=72065267626475527-127.0.0.1:60673_solr-n_0000000000) starting
   [junit4]   2> 1450181 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60673_solr
   [junit4]   2> 1450181 INFO  
(OverseerStateUpdate-72065267626475527-127.0.0.1:60673_solr-n_0000000000) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:60673_solr
   [junit4]   2> 1450182 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 1450183 INFO  
(OverseerStateUpdate-72065267626475527-127.0.0.1:60673_solr-n_0000000000) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1450184 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with 
znode version 1
   [junit4]   2> 1450184 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 0
   [junit4]   2> 1450184 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 1450184 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1450185 WARN  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_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> 1450186 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:60673_solr]
   [junit4]   2> 1450186 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1450187 DEBUG (ScheduledTrigger-14038-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> 1450187 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 1450201 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1450207 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1450208 INFO  (zkConnectionManagerCallback-14046-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1450208 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1450212 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1450215 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:60674_solr as DOWN
   [junit4]   2> 1450216 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1450216 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60674_solr
   [junit4]   2> 1450217 INFO  (zkCallback-14032-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1450217 INFO  (zkCallback-14045-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1450217 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1450218 WARN  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_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> 1450218 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60673.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450223 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60673.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450223 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60673.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450224 INFO  (jetty-launcher-14005-thread-1) 
[n:127.0.0.1:60673_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node1\.
   [junit4]   2> 1450238 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1450250 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60674.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450254 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60674.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450254 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_60674.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450255 INFO  (jetty-launcher-14005-thread-2) 
[n:127.0.0.1:60674_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node2\.
   [junit4]   2> 1450265 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1450266 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1450268 INFO  (zkConnectionManagerCallback-14060-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1450268 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1450269 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1450270 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:60659/solr ready
   [junit4]   2> 1450274 INFO  (qtp205089409-22078) [n:127.0.0.1:60673_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 1450279 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450279 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450279 DEBUG (qtp205089409-22080) [n:127.0.0.1:60673_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1450283 INFO  (qtp205089409-22080) [n:127.0.0.1:60673_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=8
   [junit4]   2> 1450283 DEBUG (zkCallback-14032-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 1450283 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 1450284 INFO  (qtp1968779148-22081) [n:127.0.0.1:60674_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> 1450285 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1450285 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 1450286 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testScheduledTrigger
   [junit4]   2> 1450287 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2> 1450288 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
   [junit4]   2> 1450289 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450290 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450292 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450293 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450294 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450295 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450296 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450297 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450298 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450300 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450301 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450303 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450305 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450307 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450307 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450307 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450311 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450312 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450313 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450314 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450315 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450316 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450317 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450318 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450319 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450320 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450321 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450322 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450323 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450324 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450326 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450331 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450332 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450334 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450335 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450336 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450338 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450339 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450340 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450342 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1450343 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
   [junit4]   2> 1450345 INFO  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
   [junit4]   2> 1450441 WARN  
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_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> 1450441 DEBUG 
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
   [junit4]   2> 1450443 INFO  (qtp1968779148-22075) [n:127.0.0.1:60674_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> 1450445 INFO  (qtp205089409-22078) [n:127.0.0.1:60673_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> 1450446 DEBUG 
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.s.c.a.PolicyHelper New session created 
   [junit4]   2> 1450448 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450449 INFO  (qtp205089409-22082) [n:127.0.0.1:60673_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> 1450451 DEBUG 
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 117090569 sessionWrapper.createTime 117090563479000, 
this.sessionWrapper.createTime 117090563479000 
   [junit4]   2> 1450454 INFO  
(OverseerStateUpdate-72065267626475527-127.0.0.1:60673_solr-n_0000000000) 
[n:127.0.0.1:60673_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:60673/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:60673_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1450456 INFO  
(OverseerStateUpdate-72065267626475527-127.0.0.1:60673_solr-n_0000000000) 
[n:127.0.0.1:60673_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:60674/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:60674_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1450458 INFO  
(OverseerStateUpdate-72065267626475527-127.0.0.1:60673_solr-n_0000000000) 
[n:127.0.0.1:60673_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:60674/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:60674_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1450638 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450638 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450639 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450639 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_solr    
x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1450649 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450650 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450650 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450670 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1450673 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1450673 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1450816 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450836 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450838 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450863 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450863 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_60673.solr.core.testScheduledTrigger.shard1.replica_n1' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450864 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
   [junit4]   2> 1450879 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450879 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_60674.solr.core.testScheduledTrigger.shard1.replica_n2' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450879 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
   [junit4]   2> 1450881 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450881 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_60674.solr.core.testScheduledTrigger.shard1.replica_n4' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4f4b8884
   [junit4]   2> 1450881 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
   [junit4]   2> 1450956 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450956 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450957 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450957 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450957 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450957 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1450960 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450961 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450961 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450961 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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 1667052352485457920
   [junit4]   2> 1450961 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450961 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450964 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450964 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1450964 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450964 INFO  
(searcherExecutor-14062-thread-1-processing-n:127.0.0.1:60673_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:60673_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1450964 INFO  
(searcherExecutor-14062-thread-1-processing-n:127.0.0.1:60673_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:60673_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> 1450965 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450965 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450965 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450965 INFO  
(searcherExecutor-14062-thread-1-processing-n:127.0.0.1:60673_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:60673_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> 1450965 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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 1667052352489652224
   [junit4]   2> 1450966 INFO  
(searcherExecutor-14066-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:60674_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1450966 INFO  
(searcherExecutor-14066-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:60674_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> 1450968 INFO  
(searcherExecutor-14066-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:60674_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> 1450968 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450968 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1450968 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450968 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1450970 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450970 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450970 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450970 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/testScheduledTrigger/terms/shard1 to 
Terms{values={core_node6=0, core_node3=0}, version=1}
   [junit4]   2> 1450970 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1450970 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450971 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1450971 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450972 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1450972 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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 1667052352496992256
   [junit4]   2> 1450972 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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=2 
timeoute in=9999ms
   [junit4]   2> 1450972 INFO  
(searcherExecutor-14064-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:60674_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1450972 INFO  
(searcherExecutor-14064-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:60674_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> 1450972 INFO  
(searcherExecutor-14064-thread-1-processing-n:127.0.0.1:60674_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:60674_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> 1450974 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/testScheduledTrigger/terms/shard1 to 
Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
   [junit4]   2> 1450974 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1451079 DEBUG (ScheduledTrigger-14038-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> 1451420 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1451420 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1451420 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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:60673/solr/testScheduledTrigger_shard1_replica_n1/
   [junit4]   2> 1451420 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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:60673/solr  
START 
replicas=[http://127.0.0.1:60674/solr/testScheduledTrigger_shard1_replica_n2/, 
http://127.0.0.1:60674/solr/testScheduledTrigger_shard1_replica_n4/] 
nUpdates=100
   [junit4]   2> 1451421 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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:60673/solr  
DONE. We have no versions. sync failed.
   [junit4]   2> 1451422 INFO  (qtp1968779148-22075) [n:127.0.0.1:60674_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> 1451422 INFO  (qtp1968779148-22150) [n:127.0.0.1:60674_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> 1451423 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1451423 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1451423 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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/72065267626475527-core_node3-n_0000000000
   [junit4]   2> 1451424 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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:60673/solr/testScheduledTrigger_shard1_replica_n1/ shard1
   [junit4]   2> 1451516 INFO  (zkCallback-14032-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> 1451517 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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> 1451521 INFO  (qtp205089409-22076) [n:127.0.0.1:60673_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=991
   [junit4]   2> 1451607 INFO  (zkCallback-14032-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> 1451607 INFO  (zkCallback-14032-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> 1451869 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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=1381
   [junit4]   2> 1451873 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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=1387
   [junit4]   2> 1451873 DEBUG 
(OverseerThreadFactory-14040-thread-1-processing-n:127.0.0.1:60673_solr) 
[n:127.0.0.1:60673_solr     ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
   [junit4]   2> 1451875 INFO  (qtp1968779148-22081) [n:127.0.0.1:60674_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> 1451961 INFO  (zkCallback-14032-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> 1451961 INFO  (zkCallback-14032-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> 1451961 INFO  (zkCallback-14045-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> 1451961 INFO  (zkCallback-14045-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> 1451961 INFO  (qtp1968779148-22081) [n:127.0.0.1:60674_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=1883
   [junit4]   2> 1451961 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testScheduledTrigger
   [junit4]   2> 1451964 INFO  (qtp1968779148-22077) [n:127.0.0.1:60674_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> 1451966 INFO  (qtp1968779148-22079) [n:127.0.0.1:60674_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> 1451968 INFO  (qtp205089409-22078) [n:127.0.0.1:60673_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> 1451970 INFO  (qtp205089409-22082) [n:127.0.0.1:60673_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
 status=0 QTime=2
   [junit4]   2> 1451970 DEBUG (qtp1968779148-22150) [n:127.0.0.1:60674_solr    
 ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1451970 INFO  (qtp1968779148-22150) [n:127.0.0.1:60674_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=9
   [junit4]   2> 1451971 DEBUG (ScheduledTrigger-14038-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> 1451971 DEBUG (zkCallback-14032-thread-2) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1451972 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 1451972 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short 
content, break even is size 23
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
2 ...
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 
2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 
14+36
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1451972 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1451972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4310c679{/solr,null,AVAILABLE}
   [junit4]   2> 1451973 DEBUG 
(OverseerAutoScalingTriggerThread-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.AbstractConnector Started ServerConnector@45758c45{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:60729}
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.s.Server Started @1406412ms
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=60729}
   [junit4]   2> 1451974 ERROR 
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 8.6.0
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-18T18:03:33.683954900Z
   [junit4]   2> 1451974 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1451976 INFO  (zkConnectionManagerCallback-14080-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1451976 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1451977 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1452069 INFO  
(OverseerCollectionConfigSetProcessor-72065267626475527-127.0.0.1:60673_solr-n_0000000000)
 [n:127.0.0.1:60673_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> 1452297 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1452299 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7f86bf80[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1452299 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@7f86bf80[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1452301 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7b33bc49[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1452301 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@7b33bc49[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1452302 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60659/solr
   [junit4]   2> 1452303 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1452305 INFO  (zkConnectionManagerCallback-14091-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1452305 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1452398 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 1452401 INFO  (zkConnectionManagerCallback-14093-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1452401 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1452405 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1452407 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:60729_solr as DOWN
   [junit4]   2> 1452408 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1452408 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:60729_solr
   [junit4]   2> 1452409 INFO  (zkCallback-14032-thread-2) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1452409 INFO  (zkCallback-14045-thread-2) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1452409 INFO  (zkCallback-14059-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1452409 INFO  (zkCallback-14092-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1452410 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1452410 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [n:127.0.0.1:60729_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> 1452427 INFO  (TEST-ScheduledTriggerIntegrat

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

per... (3) -> (2)
   [junit4]   2> 1504858 INFO  (zkCallback-14032-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 1504858 INFO  (jetty-closer-14102-thread-3) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@45758c45{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1504860 INFO  (jetty-closer-14102-thread-3) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4310c679{/solr,null,UNAVAILABLE}
   [junit4]   2> 1504860 INFO  (jetty-closer-14102-thread-3) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1505005 INFO  (jetty-closer-14102-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@7b9edf57{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1505005 INFO  (jetty-closer-14102-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@e5f0502{/solr,null,UNAVAILABLE}
   [junit4]   2> 1505005 INFO  (jetty-closer-14102-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1505146 INFO  (jetty-closer-14102-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72065267626475527-127.0.0.1:60673_solr-n_0000000000) closing
   [junit4]   2> 1505149 INFO  (jetty-closer-14102-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2fe8576{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1505149 INFO  (jetty-closer-14102-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@636725f0{/solr,null,UNAVAILABLE}
   [junit4]   2> 1505149 INFO  (jetty-closer-14102-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1505150 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1505442 WARN  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        7       
/solr/collections/testScheduledTrigger/terms/shard1
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        3       
/solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2>        3       /solr/packages.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        2       /solr/configs/testScheduledTrigger.AUTOCREATED
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        21      
/solr/collections/testScheduledTrigger/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        4       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        23      /solr/overseer/queue
   [junit4]   2>        13      /solr/live_nodes
   [junit4]   2>        7       /solr/collections
   [junit4]   2>        5       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 1505447 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:60659
   [junit4]   2> 1505447 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60659
   [junit4]   2> 1505447 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60659
   [junit4]   2> 1508320 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[C2206F58CF9E46C5])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger 
-Dtests.seed=C2206F58CF9E46C5 -Dtests.slow=true -Dtests.locale=sr-RS 
-Dtests.timezone=Asia/Pontianak -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 58.7s J0 | 
ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ScheduledTrigger did 
not fire in time
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([C2206F58CF9E46C5:513B272A91631DF1]: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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_C2206F58CF9E46C5-001
   [junit4]   2> NOTE: test params are: 
codec=FastDecompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=8, maxDocsPerChunk=248, blockShift=10), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
 chunkSize=8, blockSize=10)), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5fd8f8e1),
 locale=sr-RS, timezone=Asia/Pontianak
   [junit4]   2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 
(64-bit)/cpus=6,threads=1,free=194964752,total=517996544
   [junit4]   2> NOTE: All tests run in this JVM: [NestedAtomicUpdateTest, 
QueryParsingTest, TestFieldCollectionResource, TestPivotHelperCode, 
TestUninvertingReader, TestManagedStopFilterFactory, ExternalFileFieldSortTest, 
TestConfigSetsAPIExclusivity, BooleanFieldTest, NumericFieldsTest, FullHLLTest, 
PeerSyncWithIndexFingerprintCachingTest, TestLegacyBM25SimilarityFactory, 
TestLRUStatsCache, TestZkAclsWithHadoopAuth, TestRandomRequestDistribution, 
TestSolrTestCaseJ4, DistributedTermsComponentTest, TestSchemaResource, 
LeaderElectionContextKeyTest, TestDFISimilarityFactory, TestSimPolicyCloud, 
SharedFSAutoReplicaFailoverTest, TestCustomStream, AuthToolTest, 
TestSchemaManager, DistribJoinFromCollectionTest, TestSubQueryTransformer, 
ClassificationUpdateProcessorTest, CollectionTooManyReplicasTest, 
TestPostingsSolrHighlighter, TestJsonFacetsStatsParsing, MetricUtilsTest, 
SolrJmxReporterTest, BlockDirectoryTest, TestPKIAuthenticationPlugin, 
IndexSizeTriggerTest, TestFreeTextSuggestions, MultiSolrCloudTestCaseTest, 
TestInPlaceUpdatesStandalone, SpatialRPTFieldTypeTest, 
BlockJoinFacetDistribTest, TestCloudDeleteByQuery, SpatialFilterTest, TestJoin, 
HdfsUnloadDistributedZkTest, DeleteReplicaTest, ZkShardTermsTest, 
UpdateLogTest, TestStressThreadBackup, CoreAdminOperationTest, TestXmlQParser, 
TestHdfsBackupRestoreCore, TestFoldingMultitermQuery, TestMergePolicyConfig, 
CollectionStateFormat2Test, SimplePostToolTest, HdfsBasicDistributedZkTest, 
TestPayloadScoreQParserPlugin, QueryElevationComponentTest, ZkStateReaderTest, 
TestSimClusterStateProvider, TestRestManager, 
VMParamsZkACLAndCredentialsProvidersTest, TestTlogReplica, 
CloudExitableDirectoryReaderTest, TestSolrCoreParser, 
TestSolrCloudWithSecureImpersonation, TestMaxTokenLenTokenizer, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestConfigSetProperties, 
OverriddenZkACLAndCredentialsProvidersTest, TestManagedSchemaAPI, 
AnalysisAfterCoreReloadTest, LeaderTragicEventTest, RandomizedTaggerTest, 
TestLeaderElectionWithEmptyReplica, DistributedIntervalFacetingTest, 
DirectUpdateHandlerTest, SolrMetricManagerTest, TestStressVersions, 
TestRestoreCore, FieldAnalysisRequestHandlerTest, TestApiFramework, 
UtilsToolTest, DocExpirationUpdateProcessorFactoryTest, 
HdfsDirectoryFactoryTest, TestFaceting, TestShardHandlerFactory, 
ClusterStateUpdateTest, ConfigSetsAPITest, DeleteLastCustomShardedReplicaTest, 
ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest, 
MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, 
OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, 
RecoveryAfterSoftCommitTest, RestartWhileUpdatingTest, RollingRestartTest, 
SolrXmlInZkTest, SplitShardTest, SystemCollectionCompatTest, 
TestCloudPseudoReturnFields, TestCloudRecovery, TestDistribDocBasedVersion, 
TestExclusionRuleCollectionAccess, TestHashPartitioner, 
TestMiniSolrCloudClusterSSL, TestShortCircuitedRequests, TestWithCollection, 
TestCollectionsAPIViaSolrCloudCluster, HdfsAutoAddReplicasIntegrationTest, 
HttpTriggerListenerTest, IndexSizeTriggerSizeEstimationTest, 
NodeLostTriggerTest, ScheduledTriggerIntegrationTest]
   [junit4] Completed [583/908 (1!)] on J0 in 58.86s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 45965 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1142/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=C2206F58CF9E46C5 -Dtests.slow=true -Dtests.locale=sr-RS 
-Dtests.timezone=Asia/Pontianak -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0

[...truncated 78 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to