Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8869/
Java: 64bit/jdk-15-ea+16 -XX:+UseCompressedOops -XX:+UseG1GC

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

Error Message:
ScheduledTrigger did not fire in time

Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
        at 
__randomizedtesting.SeedInfo.seed([919C3279C8FE720C:2877A0B96032938]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
        at java.base/java.lang.Thread.run(Thread.java:832)




Build Log:
[...truncated 14961 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
   [junit4]   2> 1403493 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[919C3279C8FE720C]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
   [junit4]   2> 1403493 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[919C3279C8FE720C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1403498 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[919C3279C8FE720C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\data-dir-95-001
   [junit4]   2> 1403498 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[919C3279C8FE720C]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1403498 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[919C3279C8FE720C]-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> 1403501 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
   [junit4]   2> 1403501 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001
   [junit4]   2> 1403501 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1403502 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1403502 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1403605 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer start zk server on port: 57824
   [junit4]   2> 1403605 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57824
   [junit4]   2> 1403605 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57824
   [junit4]   2> 1403605 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57824
   [junit4]   2> 1403606 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1403609 INFO  (zkConnectionManagerCallback-14757-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403609 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1403612 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1403613 INFO  (zkConnectionManagerCallback-14759-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403613 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1403614 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1403616 INFO  (zkConnectionManagerCallback-14761-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403616 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
   [junit4]   2> 1403721 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4c21284a{/solr,null,AVAILABLE}
   [junit4]   2> 1403722 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7aa9dc90{/solr,null,AVAILABLE}
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@1983c8b1{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:57838}
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.s.Server Started @1361880ms
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57838}
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@4dab227{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:57837}
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.s.Server Started @1361880ms
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57837}
   [junit4]   2> 1403724 ERROR (jetty-launcher-14762-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1403724 ERROR (jetty-launcher-14762-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
9.0.0
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-23T06:49:09.988610Z
   [junit4]   2> 1403724 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-23T06:49:09.988610Z
   [junit4]   2> 1403725 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1403725 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1403727 INFO  (zkConnectionManagerCallback-14765-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403727 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1403727 INFO  (zkConnectionManagerCallback-14766-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403727 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1403728 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1403728 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1403836 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1403837 WARN  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3b161e34[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403837 WARN  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@3b161e34[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403839 WARN  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@418fbd52[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403839 WARN  (jetty-launcher-14762-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@418fbd52[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403840 INFO  (jetty-launcher-14762-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57824/solr
   [junit4]   2> 1403841 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1403843 INFO  (zkConnectionManagerCallback-14777-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403843 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1403916 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:57838_solr
   [junit4]   2> 1403917 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.Overseer Overseer 
(id=72090052521099269-127.0.0.1:57838_solr-n_0000000000) starting
   [junit4]   2> 1403921 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57838_solr
   [junit4]   2> 1403921 INFO  
(OverseerStateUpdate-72090052521099269-127.0.0.1:57838_solr-n_0000000000) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:57838_solr
   [junit4]   2> 1403922 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 1403923 INFO  
(OverseerStateUpdate-72090052521099269-127.0.0.1:57838_solr-n_0000000000) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1403923 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with 
znode version 1
   [junit4]   2> 1403923 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 0
   [junit4]   2> 1403923 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 1403924 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1403924 WARN  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_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> 1403926 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:57838_solr]
   [junit4]   2> 1403926 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1403927 DEBUG (ScheduledTrigger-14782-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> 1403927 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 1403949 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1403963 WARN  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@7c39acb2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403963 WARN  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@7c39acb2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403965 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1403967 WARN  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2e29a350[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403967 WARN  (jetty-launcher-14762-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@2e29a350[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1403967 INFO  (jetty-launcher-14762-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57824/solr
   [junit4]   2> 1403970 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 1403973 INFO  (zkConnectionManagerCallback-14799-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1403973 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1403980 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1403983 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:57837_solr as DOWN
   [junit4]   2> 1403987 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 1000 transient cores
   [junit4]   2> 1403987 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57837_solr
   [junit4]   2> 1403987 INFO  (zkCallback-14776-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1403987 INFO  (zkCallback-14798-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1403987 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1403987 WARN  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_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> 1403987 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57838.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1403995 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57838.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1403996 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57838.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1403996 INFO  (jetty-launcher-14762-thread-1) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node1\.
   [junit4]   2> 1404007 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1404023 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57837.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404028 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57837.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404028 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57837.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404029 INFO  (jetty-launcher-14762-thread-2) 
[n:127.0.0.1:57837_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node2\.
   [junit4]   2> 1404039 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 1404040 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1404041 INFO  (zkConnectionManagerCallback-14813-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1404041 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1404043 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1404044 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:57824/solr ready
   [junit4]   2> 1404049 INFO  (qtp169696532-23255) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1404052 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1404053 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_solr     
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 
transient cores
   [junit4]   2> 1404054 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1404055 DEBUG (qtp169696532-23253) [n:127.0.0.1:57837_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1404056 INFO  (qtp169696532-23253) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 1404056 DEBUG (zkCallback-14776-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 1404056 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 1404057 INFO  (qtp390341673-23258) [n:127.0.0.1:57838_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> 1404058 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1404059 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 1404059 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testScheduledTrigger
   [junit4]   2> 1404059 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2> 1404061 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
   [junit4]   2> 1404062 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404064 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404064 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404066 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404067 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404068 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404069 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404071 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404072 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404072 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404074 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404075 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404076 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404077 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404078 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404080 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404081 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404083 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404084 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404085 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404086 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404086 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404086 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404090 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404092 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404093 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404094 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404096 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404097 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404098 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404100 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404101 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404102 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404104 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404105 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404106 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404108 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404109 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404110 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404111 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
   [junit4]   2> 1404113 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
   [junit4]   2> 1404114 INFO  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
   [junit4]   2> 1404222 WARN  
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_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> 1404222 DEBUG 
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
   [junit4]   2> 1404226 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=2
   [junit4]   2> 1404226 INFO  (qtp169696532-23255) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1404226 DEBUG 
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.s.c.a.PolicyHelper New session created 
   [junit4]   2> 1404230 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=2
   [junit4]   2> 1404230 INFO  (qtp169696532-23257) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1404234 DEBUG 
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 495277855 sessionWrapper.createTime 495277849882000, 
this.sessionWrapper.createTime 495277849882000 
   [junit4]   2> 1404238 INFO  
(OverseerStateUpdate-72090052521099269-127.0.0.1:57838_solr-n_0000000000) 
[n:127.0.0.1:57838_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:57837/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:57837_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1404238 INFO  
(OverseerStateUpdate-72090052521099269-127.0.0.1:57838_solr-n_0000000000) 
[n:127.0.0.1:57838_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:57838/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:57838_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1404242 INFO  
(OverseerStateUpdate-72090052521099269-127.0.0.1:57838_solr-n_0000000000) 
[n:127.0.0.1:57838_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:57838/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:57838_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1404452 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404453 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404453 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404465 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 9.0.0
   [junit4]   2> 1404465 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 9.0.0
   [junit4]   2> 1404465 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 9.0.0
   [junit4]   2> 1404481 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1404481 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1404481 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 1404602 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404602 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404602 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_57837.solr.core.testScheduledTrigger.shard1.replica_n1' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404602 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
   [junit4]   2> 1404605 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404605 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404605 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_57838.solr.core.testScheduledTrigger.shard1.replica_n2' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404605 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
   [junit4]   2> 1404606 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404606 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404607 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_57838.solr.core.testScheduledTrigger.shard1.replica_n4' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1404607 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore 
[[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
   [junit4]   2> 1404670 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404670 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404672 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404672 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404672 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404672 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1404674 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404674 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404674 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1404674 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404674 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404674 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404675 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1404675 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404675 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404675 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404675 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404675 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404675 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404675 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404675 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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 1667462910117412864
   [junit4]   2> 1404676 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404676 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1404676 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404677 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404677 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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 1667462910119510016
   [junit4]   2> 1404677 INFO  
(searcherExecutor-14815-thread-1-processing-n:127.0.0.1:57837_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:57837_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1404677 INFO  
(searcherExecutor-14815-thread-1-processing-n:127.0.0.1:57837_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:57837_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> 1404677 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404677 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1404678 INFO  
(searcherExecutor-14815-thread-1-processing-n:127.0.0.1:57837_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:57837_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> 1404678 INFO  
(searcherExecutor-14817-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:57838_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1404678 INFO  
(searcherExecutor-14817-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:57838_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> 1404679 INFO  
(searcherExecutor-14817-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:57838_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> 1404679 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404679 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404679 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404680 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404680 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404680 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404680 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 1404680 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404681 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404681 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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 1667462910123704320
   [junit4]   2> 1404681 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/testScheduledTrigger/terms/shard1 to 
Terms{values={core_node3=0, core_node5=0}, version=1}
   [junit4]   2> 1404681 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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> 1404682 INFO  
(searcherExecutor-14819-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:57838_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 1404682 INFO  
(searcherExecutor-14819-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:57838_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> 1404682 INFO  
(searcherExecutor-14819-thread-1-processing-n:127.0.0.1:57838_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:57838_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> 1404683 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful 
update of terms at /collections/testScheduledTrigger/terms/shard1 to 
Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
   [junit4]   2> 1404683 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1404684 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404684 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404684 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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:57837/solr/testScheduledTrigger_shard1_replica_n1/
   [junit4]   2> 1404684 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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:57837/solr  
START 
replicas=[http://127.0.0.1:57838/solr/testScheduledTrigger_shard1_replica_n2/, 
http://127.0.0.1:57838/solr/testScheduledTrigger_shard1_replica_n4/] 
nUpdates=100
   [junit4]   2> 1404685 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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:57837/solr  
DONE. We have no versions. sync failed.
   [junit4]   2> 1404686 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_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> 1404686 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_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> 1404687 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404687 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404687 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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/72090052521099270-core_node3-n_0000000000
   [junit4]   2> 1404688 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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:57837/solr/testScheduledTrigger_shard1_replica_n1/ shard1
   [junit4]   2> 1404793 INFO  (zkCallback-14798-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> 1404793 INFO  (zkCallback-14798-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> 1404794 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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> 1404797 INFO  (qtp169696532-23251) [n:127.0.0.1:57837_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=335
   [junit4]   2> 1404899 INFO  (zkCallback-14798-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> 1404899 INFO  (zkCallback-14798-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> 1404900 INFO  (zkCallback-14798-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testScheduledTrigger/state.json] for collection 
[testScheduledTrigger] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1404958 DEBUG (ScheduledTrigger-14782-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> 1405720 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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=1230
   [junit4]   2> 1405720 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_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=1230
   [junit4]   2> 1405720 DEBUG 
(OverseerThreadFactory-14784-thread-1-processing-n:127.0.0.1:57838_solr) 
[n:127.0.0.1:57838_solr     ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
   [junit4]   2> 1405722 INFO  (qtp390341673-23258) [n:127.0.0.1:57838_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> 1405821 INFO  (zkCallback-14798-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> 1405821 INFO  (zkCallback-14776-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> 1405821 INFO  (zkCallback-14798-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> 1405821 INFO  (zkCallback-14776-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> 1405821 INFO  (zkCallback-14798-thread-3) [     ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/testScheduledTrigger/state.json] for collection 
[testScheduledTrigger] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1405822 INFO  (qtp390341673-23258) [n:127.0.0.1:57838_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=1714
   [junit4]   2> 1405822 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testScheduledTrigger
   [junit4]   2> 1405825 INFO  (qtp390341673-23252) [n:127.0.0.1:57838_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> 1405826 INFO  (qtp390341673-23256) [n:127.0.0.1:57838_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1405827 INFO  (qtp169696532-23253) [n:127.0.0.1:57837_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> 1405828 INFO  (qtp169696532-23255) [n:127.0.0.1:57837_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node}
 status=0 QTime=1
   [junit4]   2> 1405828 DEBUG (qtp390341673-23254) [n:127.0.0.1:57838_solr     
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1405829 INFO  (qtp390341673-23254) [n:127.0.0.1:57838_solr     
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 1405829 DEBUG (zkCallback-14776-thread-3) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1405829 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 1405829 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 1405829 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
2 ...
   [junit4]   2> 1405829 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 1405829 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 
2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 
15-ea+16-681
   [junit4]   2> 1405829 DEBUG 
(OverseerAutoScalingTriggerThread-72090052521099269-127.0.0.1:57838_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 1405829 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1405829 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1405830 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1405830 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@be6dd2b{/solr,null,AVAILABLE}
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.AbstractConnector Started ServerConnector@22bf0f40{HTTP/1.1, 
(http/1.1, h2c)}{127.0.0.1:57962}
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.s.Server Started @1363926ms
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=57962}
   [junit4]   2> 1405832 ERROR 
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 9.0.0
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
   [junit4]   2> 1405832 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-05-23T06:49:12.096304800Z
   [junit4]   2> 1405833 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1405835 INFO  (zkConnectionManagerCallback-14833-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1405835 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1405835 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1405952 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1405953 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5efc5d24[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1405953 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@5efc5d24[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1405956 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@2c2d3e38[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1405956 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@2c2d3e38[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1405957 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57824/solr
   [junit4]   2> 1405958 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 1405960 INFO  (zkConnectionManagerCallback-14844-thread-1) [   
  ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1405960 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 1405965 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1405968 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:57962_solr as DOWN
   [junit4]   2> 1405968 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 1000 transient cores
   [junit4]   2> 1405968 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:57962_solr
   [junit4]   2> 1405969 INFO  (zkCallback-14798-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1405969 INFO  (zkCallback-14776-thread-3) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1405970 INFO  (zkCallback-14812-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1405970 INFO  (zkCallback-14843-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1405972 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 1405972 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_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> 1405989 DEBUG (ScheduledTrigger-14782-thread-2) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3 and last live nodes: 2
   [junit4]   2> 1405993 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1406011 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57962.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1406019 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57962.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1406019 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_57962.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@219246c4
   [junit4]   2> 1406020 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [n:127.0.0.1:57962_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001\tempDir-001\node3\.
   [junit4]   2> 1406028 INFO  (TEST-ScheduledTriggerIntegratio

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

executor now
   [junit4]   2> 1465252 DEBUG (closeThreadPool-14870-thread-1) [     ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
   [junit4]   2> 1465252 DEBUG (closeThreadPool-14870-thread-1) [     ] 
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool 
executor
   [junit4]   2> 1465252 DEBUG (closeThreadPool-14870-thread-1) [     ] 
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
   [junit4]   2> 1465252 DEBUG (closeThreadPool-14870-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 1465252 INFO  (closeThreadPool-14870-thread-2) [     ] 
o.a.s.c.Overseer Overseer 
(id=72090052521099269-127.0.0.1:57838_solr-n_0000000000) closing
   [junit4]   2> 1465368 INFO  (jetty-closer-14853-thread-3) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@22bf0f40{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1465368 INFO  (jetty-closer-14853-thread-3) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@be6dd2b{/solr,null,UNAVAILABLE}
   [junit4]   2> 1465368 INFO  (jetty-closer-14853-thread-3) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1465503 INFO  (jetty-closer-14853-thread-1) [     ] 
o.a.s.c.Overseer Overseer 
(id=72090052521099269-127.0.0.1:57838_solr-n_0000000000) closing
   [junit4]   2> 1465504 INFO  (jetty-closer-14853-thread-1) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@1983c8b1{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1465504 INFO  (jetty-closer-14853-thread-1) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7aa9dc90{/solr,null,UNAVAILABLE}
   [junit4]   2> 1465504 INFO  (jetty-closer-14853-thread-1) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1465642 INFO  (jetty-closer-14853-thread-2) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@4dab227{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:0}
   [junit4]   2> 1465642 INFO  (jetty-closer-14853-thread-2) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4c21284a{/solr,null,UNAVAILABLE}
   [junit4]   2> 1465642 INFO  (jetty-closer-14853-thread-2) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1465643 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1465917 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>        25      
/solr/collections/testScheduledTrigger/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        4       /solr/autoscaling.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        23      /solr/overseer/queue
   [junit4]   2>        14      /solr/live_nodes
   [junit4]   2>        7       /solr/collections
   [junit4]   2>        4       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 1465922 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57824
   [junit4]   2> 1465922 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57824
   [junit4]   2> 1465922 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57824
   [junit4]   2> 1468623 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[919C3279C8FE720C])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger 
-Dtests.seed=919C3279C8FE720C -Dtests.slow=true -Dtests.locale=fr-NC 
-Dtests.timezone=America/Puerto_Rico -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252
   [junit4] FAILURE 65.1s J3 | 
ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ScheduledTrigger did 
not fire in time
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([919C3279C8FE720C:2877A0B96032938]:0)
   [junit4]    >        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:832)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_919C3279C8FE720C-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene86): {}, 
docValues:{}, maxPointsInLeafNode=1518, maxMBSortInHeap=7.999054472646282, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1710247f),
 locale=fr-NC, timezone=America/Puerto_Rico
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea 
(64-bit)/cpus=6,threads=1,free=158442072,total=321912832
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestMultiValuedNumericRangeQuery, OverseerRolesTest, TestInitParams, 
ShardSplitTest, AutoscalingHistoryHandlerTest, TestFieldCacheSortRandom, 
DocValuesNotIndexedTest, TestComplexPhraseQParserPlugin, HttpPartitionTest, 
SoftAutoCommitTest, TestCollationField, TolerantUpdateProcessorTest, 
JvmMetricsTest, SolrCloudExampleTest, TemplateUpdateProcessorTest, 
TestMiniSolrCloudClusterSSL, DeleteLastCustomShardedReplicaTest, 
DistributedQueryElevationComponentTest, TestJsonRequest, 
TestGraphTermsQParserPlugin, V2ApiIntegrationTest, SpatialFilterTest, 
MoreLikeThisHandlerTest, PolyFieldTest, LoggingHandlerTest, 
TestSimpleTrackingShardHandler, TestLRUStatsCache, 
AutoAddReplicasPlanActionTest, AtomicUpdateProcessorFactoryTest, 
TestExtendedDismaxParser, TestXIncludeConfig, TestHdfsCloudBackupRestore, 
RestartWhileUpdatingTest, TestMacros, TlogReplayBufferedWhileIndexingTest, 
SolrCloudReportersTest, NodeMarkersRegistrationTest, TestConfig, 
DistributedFacetPivotSmallTest, TestSchemalessBufferedUpdates, 
TestSolr4Spatial2, TestCloudPhrasesIdentificationComponent, 
PeerSyncWithBufferUpdatesTest, TestDynamicFieldNamesIndexCorrectly, 
RangeFacetCloudTest, InfixSuggestersTest, TransactionLogTest, 
JWTVerificationkeyResolverTest, MetricTriggerTest, ClusterStateUpdateTest, 
TestGroupingSearch, TestRemoteStreaming, AddReplicaTest, TaggerTest, 
TestEmbeddedSolrServerConstructors, SolrCoreCheckLockOnStartupTest, 
HttpTriggerListenerTest, SuggestComponentContextFilterQueryTest, 
DistributedUpdateProcessorTest, TestSolrQueryParser, 
TestLegacyBM25SimilarityFactory, TestDistributedStatsComponentCardinality, 
NodeMutatorTest, CopyFieldTest, TestCoreContainer, HighlighterMaxOffsetTest, 
QueryResultKeyTest, ConfigSetsAPITest, TestDFISimilarityFactory, 
FileBasedSpellCheckerTest, TestLRUStatsCacheCloud, TestJsonFacetErrors, 
TestTrieFacet, PrimUtilsTest, LeaderFailoverAfterPartitionTest, 
BasicAuthStandaloneTest, TestManagedSynonymGraphFilterFactory, 
CdcrWithNodesRestartsTest, TestCopyFieldCollectionResource, TestStressReorder, 
TestCursorMarkWithoutUniqueKey, CoreSorterTest, ShardRoutingCustomTest, 
CollectionsAPIDistributedZkTest, BasicZkTest, UUIDFieldTest, 
DistribCursorPagingTest, DistribJoinFromCollectionTest, 
DistributedVersionInfoTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest, 
ReindexCollectionTest, TestDistribDocBasedVersion, TestLockTree, 
TestRSAKeyPair, TestRequestForwarding, TestStressInPlaceUpdates, 
ZkFailoverTest, AsyncCallRequestStatusResponseTest, 
CollectionTooManyReplicasTest, HdfsCollectionsAPIDistributedZkTest, 
TestReplicaProperties, NodeLostTriggerIntegrationTest, 
ScheduledTriggerIntegrationTest]
   [junit4] Completed [571/913 (1!)] on J3 in 65.15s, 1 test, 1 failure <<< 
FAILURES!

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

[repro] Revision: 675956c0041b18d48a7c059ea458c49f5310d74a

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

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

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

[...truncated 3158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=919C3279C8FE720C 
-Dtests.slow=true -Dtests.locale=fr-NC -Dtests.timezone=America/Puerto_Rico 
-Dtests.asserts=true -Dtests.file.encoding=Cp1252

[...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: builds-unsubscr...@lucene.apache.org
For additional commands, e-mail: builds-h...@lucene.apache.org

Reply via email to