You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/24 00:37:37 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-11.0.6) - Build # 1165
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1165/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([78E792F1737D5C6F:EBFCDA832D80075B]: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:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15295 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1563438 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[78E792F1737D5C6F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1563438 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[78E792F1737D5C6F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1563438 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[78E792F1737D5C6F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\data-dir-123-001
[junit4] 2> 1563453 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[78E792F1737D5C6F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1563453 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[78E792F1737D5C6F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1563462 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1563465 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001
[junit4] 2> 1563465 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1563465 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1563465 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1563582 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer start zk server on port: 55507
[junit4] 2> 1563582 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55507
[junit4] 2> 1563582 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55507
[junit4] 2> 1563582 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55507
[junit4] 2> 1563588 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1563605 INFO (zkConnectionManagerCallback-11287-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1563605 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1563635 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1563651 INFO (zkConnectionManagerCallback-11289-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1563651 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1563654 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1563674 INFO (zkConnectionManagerCallback-11291-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1563674 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1563793 WARN (jetty-launcher-11292-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1563793 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1563793 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1563793 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1563793 WARN (jetty-launcher-11292-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1563794 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@533ac642{/solr,null,AVAILABLE}
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ec9aabd{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?????}
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-1) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1563796 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55520}
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64b094f8{/solr,null,AVAILABLE}
[junit4] 2> 1563797 ERROR (jetty-launcher-11292-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1563797 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T00:06:54.599163300Z
[junit4] 2> 1563798 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2c2f5896{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?????}
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55527}
[junit4] 2> 1563799 ERROR (jetty-launcher-11292-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T00:06:54.601330800Z
[junit4] 2> 1563799 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1563803 INFO (zkConnectionManagerCallback-11296-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1563803 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1563806 INFO (zkConnectionManagerCallback-11294-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1563806 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1563806 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1563806 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1564021 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1564023 WARN (jetty-launcher-11292-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@74ffc1a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564023 WARN (jetty-launcher-11292-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@74ffc1a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564028 WARN (jetty-launcher-11292-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40c5e851[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564028 WARN (jetty-launcher-11292-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40c5e851[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564029 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55507/solr
[junit4] 2> 1564030 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1564041 INFO (zkConnectionManagerCallback-11307-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1564041 INFO (jetty-launcher-11292-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1564123 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1564125 WARN (jetty-launcher-11292-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d8d524[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564125 WARN (jetty-launcher-11292-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d8d524[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564132 WARN (jetty-launcher-11292-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77131c15[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564132 WARN (jetty-launcher-11292-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77131c15[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1564133 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55507/solr
[junit4] 2> 1564135 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1564139 INFO (zkConnectionManagerCallback-11318-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1564139 INFO (jetty-launcher-11292-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1564149 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1564158 INFO (zkConnectionManagerCallback-11320-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1564158 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1564256 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1564262 INFO (zkConnectionManagerCallback-11324-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1564262 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1564288 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55520_solr
[junit4] 2> 1564289 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.Overseer Overseer (id=72093993725984776-127.0.0.1:55520_solr-n_0000000000) starting
[junit4] 2> 1564315 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55520_solr
[junit4] 2> 1564315 INFO (OverseerStateUpdate-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [n:127.0.0.1:55520_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55520_solr
[junit4] 2> 1564316 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1564326 INFO (OverseerStateUpdate-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1564326 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1564326 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1564326 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1564326 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1564326 WARN (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_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> 1564340 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:55520_solr]
[junit4] 2> 1564342 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1564342 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1564342 DEBUG (ScheduledTrigger-11329-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> 1564364 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1564391 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55520.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564398 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55520.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564398 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55520.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564402 INFO (jetty-launcher-11292-thread-1) [n:127.0.0.1:55520_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node1\.
[junit4] 2> 1564535 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1564552 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55527_solr as DOWN
[junit4] 2> 1564557 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1564557 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55527_solr
[junit4] 2> 1564565 INFO (zkCallback-11323-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1564565 INFO (zkCallback-11319-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1564596 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1564597 WARN (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_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> 1564627 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1564652 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55527.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564658 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55527.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564658 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55527.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1564663 INFO (jetty-launcher-11292-thread-2) [n:127.0.0.1:55527_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node2\.
[junit4] 2> 1564732 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1564736 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1564752 INFO (zkConnectionManagerCallback-11347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1564752 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1564785 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1564788 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55507/solr ready
[junit4] 2> 1564837 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1564847 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1564850 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1564852 INFO (qtp585675451-17540) [n:127.0.0.1:55527_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1564856 DEBUG (qtp939212-17542) [n:127.0.0.1:55520_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1564867 INFO (qtp939212-17542) [n:127.0.0.1:55520_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=27
[junit4] 2> 1564874 INFO (qtp939212-17541) [n:127.0.0.1:55520_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> 1564878 DEBUG (zkCallback-11323-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1564878 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1564897 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1564898 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1564904 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1564905 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1564912 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1564917 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564923 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564931 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564935 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564948 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564951 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564962 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564975 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1564989 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565031 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565052 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565063 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565067 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565070 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565076 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565086 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565097 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565132 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565159 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565182 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565208 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565246 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565282 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565316 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565344 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565349 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565356 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565377 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565383 DEBUG (ScheduledTrigger-11329-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> 1565387 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565391 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565407 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565411 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565413 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565419 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565419 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565426 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565451 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565468 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565478 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565501 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1565540 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1565561 INFO (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1565699 WARN (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_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> 1565718 DEBUG (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1565737 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1565745 INFO (qtp585675451-17547) [n:127.0.0.1:55527_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1565745 DEBUG (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1565757 INFO (qtp939212-17542) [n:127.0.0.1:55520_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> 1565764 INFO (qtp585675451-17546) [n:127.0.0.1:55527_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1565767 DEBUG (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 555417352 sessionWrapper.createTime 555417332804800, this.sessionWrapper.createTime 555417332804800
[junit4] 2> 1565788 INFO (OverseerStateUpdate-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [n:127.0.0.1:55520_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:55527/solr",
[junit4] 2> "node_name":"127.0.0.1:55527_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1565795 INFO (OverseerStateUpdate-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [n:127.0.0.1:55520_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:55520/solr",
[junit4] 2> "node_name":"127.0.0.1:55520_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1565797 INFO (OverseerStateUpdate-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [n:127.0.0.1:55520_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:55520/solr",
[junit4] 2> "node_name":"127.0.0.1:55520_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1566011 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566016 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566018 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566036 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1566039 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1566039 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1566075 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1566075 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1566075 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1566318 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566318 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566324 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566402 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566402 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55520.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1566402 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1566408 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566408 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55520.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1566408 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1566408 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566408 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55527.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17804fe0
[junit4] 2> 1566408 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1566431 DEBUG (ScheduledTrigger-11329-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1566589 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566589 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566589 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566589 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566593 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566593 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1566593 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566594 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1566596 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566596 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566597 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566597 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566597 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566597 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566597 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566597 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566599 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566599 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1566600 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566600 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566601 INFO (qtp585675451-17545) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1566601 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1566601 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566601 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566602 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566603 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566603 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566603 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566603 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566603 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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 1667528201239265280
[junit4] 2> 1566603 INFO (qtp939212-17539) [n:127.0.0.1:55520_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 1667528201239265280
[junit4] 2> 1566604 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566604 INFO (qtp939212-17543) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1566604 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566605 INFO (searcherExecutor-11353-thread-1-processing-n:127.0.0.1:55527_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55527_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1566605 INFO (searcherExecutor-11353-thread-1-processing-n:127.0.0.1:55527_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55527_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> 1566605 INFO (searcherExecutor-11349-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1566605 INFO (searcherExecutor-11349-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55520_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> 1566606 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566606 INFO (qtp939212-17543) [n:127.0.0.1:55520_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 1667528201242411008
[junit4] 2> 1566606 INFO (searcherExecutor-11349-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55520_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> 1566606 INFO (searcherExecutor-11353-thread-1-processing-n:127.0.0.1:55527_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55527_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> 1566607 INFO (searcherExecutor-11351-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1566607 INFO (searcherExecutor-11351-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55520_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> 1566608 INFO (searcherExecutor-11351-thread-1-processing-n:127.0.0.1:55520_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55520_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> 1566616 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566616 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566616 INFO (qtp939212-17539) [n:127.0.0.1:55520_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1566622 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566622 INFO (qtp939212-17539) [n:127.0.0.1:55520_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> 1566625 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566625 INFO (qtp939212-17543) [n:127.0.0.1:55520_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> 1566633 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566633 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566633 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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:55527/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1566634 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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:55527/solr START replicas=[http://127.0.0.1:55520/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:55520/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1566634 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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:55527/solr DONE. We have no versions. sync failed.
[junit4] 2> 1566636 INFO (qtp939212-17614) [n:127.0.0.1:55520_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> 1566636 INFO (qtp939212-17542) [n:127.0.0.1:55520_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> 1566638 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566638 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566638 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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/72093993725984775-core_node3-n_0000000000
[junit4] 2> 1566641 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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:55527/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1566760 INFO (zkCallback-11319-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> 1566761 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1566769 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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=714
[junit4] 2> 1566881 INFO (zkCallback-11319-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> 1566881 INFO (zkCallback-11319-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> 1567558 DEBUG (ScheduledTrigger-11329-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1567819 INFO (qtp939212-17543) [n:127.0.0.1:55520_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=1629
[junit4] 2> 1567827 INFO (qtp939212-17539) [n:127.0.0.1:55520_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=1634
[junit4] 2> 1567828 DEBUG (OverseerThreadFactory-11331-thread-1-processing-n:127.0.0.1:55520_solr) [n:127.0.0.1:55520_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1567831 INFO (qtp939212-17541) [n:127.0.0.1:55520_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> 1567941 INFO (zkCallback-11323-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> 1567941 INFO (zkCallback-11323-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> 1567943 INFO (zkCallback-11319-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> 1567943 INFO (zkCallback-11319-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> 1567943 INFO (qtp939212-17541) [n:127.0.0.1:55520_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=2833
[junit4] 2> 1567943 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1567951 INFO (qtp939212-17542) [n:127.0.0.1:55520_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> 1567953 INFO (qtp939212-17614) [n:127.0.0.1:55520_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> 1567955 INFO (qtp585675451-17545) [n:127.0.0.1:55527_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> 1567959 INFO (qtp585675451-17540) [n:127.0.0.1:55527_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=2
[junit4] 2> 1567959 DEBUG (qtp585675451-17547) [n:127.0.0.1:55527_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1567970 INFO (qtp585675451-17547) [n:127.0.0.1:55527_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=19
[junit4] 2> 1567971 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1567971 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1567973 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@58bc1925{/solr,null,AVAILABLE}
[junit4] 2> 1567975 DEBUG (zkCallback-11323-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1567975 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2107317f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?????}
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55612}
[junit4] 2> 1567987 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T00:06:58.789272500Z
[junit4] 2> 1567987 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1567987 DEBUG (OverseerAutoScalingTriggerThread-72093993725984776-127.0.0.1:55520_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1567987 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1567993 INFO (zkConnectionManagerCallback-11367-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1567993 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1567994 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1568413 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1568415 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@47ed42f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1568415 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@47ed42f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1568421 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@554a2b66[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1568421 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@554a2b66[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1568421 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55507/solr
[junit4] 2> 1568425 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1568434 INFO (zkConnectionManagerCallback-11378-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1568434 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1568578 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1568588 INFO (zkConnectionManagerCallback-11380-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1568588 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1568655 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1568668 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55612_solr as DOWN
[junit4] 2> 1568673 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1568673 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55612_solr
[junit4] 2> 1568683 INFO (zkCallback-11346-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1568683 INFO (zkCallback-11319-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1568686 INFO (zkCallback-11379-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1568687 INFO (zkCallback-11323-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1568704 DEBUG (ScheduledTrigger-11329-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> 1568721 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1568721 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_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> 1568800 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [n:127.0.0.1:55612_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1568832 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F173
[...truncated too long message...]
closeThreadPool-11405-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 1625070 DEBUG (closeThreadPool-11405-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1625070 DEBUG (closeThreadPool-11405-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1625070 INFO (closeThreadPool-11405-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72093993725984776-127.0.0.1:55520_solr-n_0000000000) closing
[junit4] 2> 1625167 INFO (jetty-closer-11389-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2107317f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?}
[junit4] 2> 1625171 INFO (jetty-closer-11389-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@58bc1925{/solr,null,UNAVAILABLE}
[junit4] 2> 1625176 INFO (jetty-closer-11389-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1625271 INFO (jetty-closer-11389-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72093993725984776-127.0.0.1:55520_solr-n_0000000000) closing
[junit4] 2> 1625271 INFO (jetty-closer-11389-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@ec9aabd{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?}
[junit4] 2> 1625271 INFO (jetty-closer-11389-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@533ac642{/solr,null,UNAVAILABLE}
[junit4] 2> 1625271 INFO (jetty-closer-11389-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1625415 INFO (jetty-closer-11389-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2c2f5896{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:?}
[junit4] 2> 1625417 INFO (jetty-closer-11389-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@64b094f8{/solr,null,UNAVAILABLE}
[junit4] 2> 1625417 INFO (jetty-closer-11389-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1625419 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1625648 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1625662 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:55507
[junit4] 2> 1625662 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55507
[junit4] 2> 1625662 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55507
[junit4] 2> 1627911 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[78E792F1737D5C6F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=78E792F1737D5C6F -Dtests.slow=true -Dtests.locale=uz-Arab -Dtests.timezone=Africa/Maputo -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 64.5s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([78E792F1737D5C6F:EBFCDA832D80075B]: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:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_78E792F1737D5C6F-001
[junit4] 2> NOTE: test params are: codec=Lucene86, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@73de4f9f), locale=uz-Arab, timezone=Africa/Maputo
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=171823768,total=515899392
[junit4] 2> NOTE: All tests run in this JVM: [TestSimExtremeIndexing, TestRTGBase, TestPerFieldSimilarityWithDefaultOverride, TestCollectionAPI, TestCloudDeleteByQuery, HdfsUnloadDistributedZkTest, CloudExitableDirectoryReaderTest, TestSearcherReuse, TestWriterPerf, IgnoreLargeDocumentProcessorFactoryTest, CachingDirectoryFactoryTest, TestStressThreadBackup, CreateCollectionCleanupTest, TestCloudRecovery, VMParamsZkACLAndCredentialsProvidersTest, SearchHandlerTest, DateMathParserTest, TestUseDocValuesAsStored2, TestSort, UpdateRequestProcessorFactoryTest, TestManagedStopFilterFactory, LeaderTragicEventTest, MultiSolrCloudTestCaseTest, TestBinaryResponseWriter, TestLMDirichletSimilarityFactory, TestApiFramework, TestLeaderElectionWithEmptyReplica, TestSafeXMLParsing, TestFaceting, ZookeeperStatusHandlerTest, TestShardHandlerFactory, TokenizerChainTest, TestPackages, TestBM25SimilarityFactory, ClassificationUpdateProcessorFactoryTest, TestCharFilters, StatelessScriptUpdateProcessorFactoryTest, QueryParsingTest, SpellCheckComponentTest, CollectionTooManyReplicasTest, TestConfigSetsAPIExclusivity, BitVectorTest, LeaderElectionContextKeyTest, TestJettySolrRunner, TestFieldCacheSort, DirectSolrSpellCheckerTest, TestJsonFacetRefinement, TestCustomStream, TestValueSourceCache, ZkShardTermsTest, ReplaceNodeNoTargetTest, BasicAuthIntegrationTest, TestStressUserVersions, TestSolrCloudWithSecureImpersonation, SolrJmxReporterTest, TestMinHashQParser, TestPrepRecovery, SpellPossibilityIteratorTest, TestRandomRequestDistribution, BaseCdcrDistributedZkTest, TestImplicitCoreProperties, SplitShardTest, TestJoin, TestImpersonationWithHadoopAuth, CollectionStateFormat2Test, XsltUpdateRequestHandlerTest, SpatialRPTFieldTypeTest, MigrateRouteKeyTest, RandomizedTaggerTest, AnalysisAfterCoreReloadTest, TestSolrCachePerf, TestExactSharedStatsCache, OverriddenZkACLAndCredentialsProvidersTest, FieldAnalysisRequestHandlerTest, TimeRoutedAliasUpdateProcessorTest, TestMergePolicyConfig, ExecutePlanActionTest, CursorMarkTest, DistributedTermsComponentTest, TestSchemaResource, GraphQueryTest, CoreAdminOperationTest, PeerSyncTest, TestSolrTestCaseJ4, DistributedFacetPivotLongTailTest, HdfsLockFactoryTest, WrapperMergePolicyFactoryTest, SolrInfoBeanTest, DistributedIntervalFacetingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, TestDistributedMissingSort, TestDistributedSearch, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, DistribCursorPagingTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest, HttpPartitionWithTlogReplicasTest, ReindexCollectionTest, TestWaitForStateWithJettyShutdowns, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, AutoAddReplicasIntegrationTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, NodeLostTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [555/910 (1!)] on J0 in 64.63s, 1 test, 1 failure <<< FAILURES!
[...truncated 49014 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1165/consoleText
[repro] Revision: 5e7be63ca7fbd234526fceb2d7a0594a54f90670
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3486 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=78E792F1737D5C6F -Dtests.slow=true -Dtests.locale=uz-Arab -Dtests.timezone=Africa/Maputo -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...]
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-11.0.6) - Build # 1166
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1166/
Java: 64bit/jdk-11.0.6 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([90FE65378CC10EF8:3E52D45D23C55CC]: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:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15147 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1218746 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1218746 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1218748 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\data-dir-112-001
[junit4] 2> 1218748 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 1218748 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1218750 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 1218753 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1218754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001
[junit4] 2> 1218754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1218755 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1218755 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer start zk server on port: 52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52720
[junit4] 2> 1218901 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218906 INFO (zkConnectionManagerCallback-9101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218906 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1218919 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218922 INFO (zkConnectionManagerCallback-9103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218922 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1218924 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218926 INFO (zkConnectionManagerCallback-9105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218926 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219079 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1219079 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fa223b1{/solr,null,AVAILABLE}
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a72d9a5{/solr,null,AVAILABLE}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37e690d1{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52733}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.Server Started @1182745ms
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52733}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@504bf840{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52734}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.Server Started @1182745ms
[junit4] 2> 1219084 ERROR (jetty-launcher-9106-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52734}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:51.100036700Z
[junit4] 2> 1219084 ERROR (jetty-launcher-9106-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:51.100751800Z
[junit4] 2> 1219085 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219085 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219088 INFO (zkConnectionManagerCallback-9110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219088 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219088 INFO (zkConnectionManagerCallback-9108-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219088 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219089 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1219089 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1219434 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1219440 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@45320ac8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219444 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@45320ac8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@57c30fc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@57c30fc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1219451 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219454 INFO (zkConnectionManagerCallback-9124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219454 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219562 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1219567 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7e136e4d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219567 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7e136e4d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d64ef65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d64ef65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1219579 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219581 INFO (zkConnectionManagerCallback-9132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219581 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219600 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219604 INFO (zkConnectionManagerCallback-9134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219606 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219732 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52734_solr
[junit4] 2> 1219732 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.Overseer Overseer (id=72095075713155079-127.0.0.1:52734_solr-n_0000000000) starting
[junit4] 2> 1219742 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52734_solr
[junit4] 2> 1219742 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:52734_solr
[junit4] 2> 1219742 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1219744 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1219746 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219746 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1219748 WARN (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_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> 1219753 INFO (zkConnectionManagerCallback-9143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219753 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219753 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:52734_solr]
[junit4] 2> 1219754 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1219755 DEBUG (ScheduledTrigger-9139-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> 1219755 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1219762 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1219768 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52733_solr as DOWN
[junit4] 2> 1219769 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1219769 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52733_solr
[junit4] 2> 1219771 INFO (zkCallback-9133-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1219771 INFO (zkCallback-9142-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1219772 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1219772 WARN (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_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> 1219774 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1219798 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1219801 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.
[junit4] 2> 1219817 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219824 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219824 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219826 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.
[junit4] 2> 1219856 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1219856 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219859 INFO (zkConnectionManagerCallback-9161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219859 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219861 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1219861 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52720/solr ready
[junit4] 2> 1219883 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1219902 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1219912 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1219916 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1219916 DEBUG (qtp1521718064-12883) [n:127.0.0.1:52734_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1219916 INFO (qtp1521718064-12883) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 1219916 DEBUG (zkCallback-9133-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1219919 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1219921 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1219922 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1219927 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_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> 1219931 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1219931 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1219934 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1219936 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219938 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219940 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219941 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219944 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219946 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219948 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219950 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219951 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219953 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219956 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219957 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219959 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219961 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219963 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219965 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219967 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219969 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219971 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219973 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219975 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219977 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219979 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219981 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219990 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219991 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219993 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219995 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219995 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1219999 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220000 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220002 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220004 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220005 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220007 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220009 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1220011 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1220011 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1220161 WARN (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_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> 1220161 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1220166 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220169 INFO (qtp1521718064-12881) [n:127.0.0.1:52734_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> 1220171 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1220174 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_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> 1220177 INFO (qtp1521718064-12879) [n:127.0.0.1:52734_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> 1220177 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 571925961 sessionWrapper.createTime 571925955735800, this.sessionWrapper.createTime 571925955735800
[junit4] 2> 1220182 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_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":"https://127.0.0.1:52734/solr",
[junit4] 2> "node_name":"127.0.0.1:52734_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220182 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_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":"https://127.0.0.1:52733/solr",
[junit4] 2> "node_name":"127.0.0.1:52733_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220188 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_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":"https://127.0.0.1:52733/solr",
[junit4] 2> "node_name":"127.0.0.1:52733_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220478 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220478 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220488 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220495 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220495 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220507 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220517 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220521 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220543 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220701 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220719 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220726 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220749 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220750 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220750 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1220870 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220870 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220874 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220874 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220875 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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 1667546127156641792
[junit4] 2> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_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> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_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> 1220886 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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}, version=0}
[junit4] 2> 1220886 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1220886 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220886 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220892 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoute in=9999ms
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220892 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220892 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220897 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220897 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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 1667546127178661888
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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 1667546127178661888
[junit4] 2> 1220903 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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_node6=0, core_node3=0}, version=1}
[junit4] 2> 1220903 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_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> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_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> 1220906 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1220906 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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> 1221193 DEBUG (ScheduledTrigger-9139-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> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n4/
[junit4] 2> 1221612 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:52733/solr START replicas=[https://127.0.0.1:52734/solr/testScheduledTrigger_shard1_replica_n1/, https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n2/] nUpdates=100
[junit4] 2> 1221613 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:52733/solr DONE. We have no versions. sync failed.
[junit4] 2> 1221623 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_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> 1221623 INFO (qtp1521718064-12879) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 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> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72095075713155080-core_node6-n_0000000000
[junit4] 2> 1221625 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n4/ shard1
[junit4] 2> 1221771 INFO (zkCallback-9142-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> 1221773 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1221780 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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=907
[junit4] 2> 1221919 INFO (zkCallback-9142-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> 1221919 INFO (zkCallback-9142-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> 1222346 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_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=1296
[junit4] 2> 1222347 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_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=1303
[junit4] 2> 1222347 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1222349 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_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> 1222491 INFO (zkCallback-9142-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> 1222491 INFO (zkCallback-9133-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> 1222491 INFO (zkCallback-9142-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> 1222492 INFO (zkCallback-9133-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> 1222492 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_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=1788
[junit4] 2> 1222492 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1222497 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_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> 1222499 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_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> 1222500 INFO (qtp1521718064-12881) [n:127.0.0.1:52734_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> 1222503 INFO (qtp1521718064-12883) [n:127.0.0.1:52734_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> 1222503 DEBUG (qtp1025440673-12951) [n:127.0.0.1:52733_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1222503 INFO (qtp1025440673-12951) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1222504 DEBUG (zkCallback-9133-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1222504 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1222505 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1222505 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1222507 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ea3fce2{/solr,null,AVAILABLE}
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@654e73d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52831}
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.Server Started @1185134ms
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52831}
[junit4] 2> 1222511 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:54.527815200Z
[junit4] 2> 1222513 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1222515 INFO (zkConnectionManagerCallback-9181-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1222515 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1222516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1222630 DEBUG (ScheduledTrigger-9139-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1222802 INFO (OverseerCollectionConfigSetProcessor-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1222881 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1222884 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c8cff90[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222884 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c8cff90[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222889 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f1ad2aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222889 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f1ad2aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222891 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1222892 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1222895 INFO (zkConnectionManagerCallback-9192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1222895 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223042 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223046 INFO (zkConnectionManagerCallback-9194-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223046 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223053 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1223059 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52831_solr as DOWN
[junit4] 2> 1223062 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1223062 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52831_solr
[junit4] 2> 1223064 INFO (zkCallback-9160-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9133-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9142-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9193-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1223064 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_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> 1223108 INFO (TEST-ScheduledTriggerIntegrationTest.testS
[...truncated too long message...]
(jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1275642 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33ef8cc: rootName = solr_52733, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1803418e
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a30f091: rootName = solr_52733, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1394bf04
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1275722 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@654e73d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275723 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4ea3fce2{/solr,null,UNAVAILABLE}
[junit4] 2> 1275723 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275816 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@37e690d1{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275817 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1fa223b1{/solr,null,UNAVAILABLE}
[junit4] 2> 1275817 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275911 INFO (jetty-closer-9203-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72095075713155079-127.0.0.1:52734_solr-n_0000000000) closing
[junit4] 2> 1275912 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@504bf840{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275912 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a72d9a5{/solr,null,UNAVAILABLE}
[junit4] 2> 1275913 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275913 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1276104 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> 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> 22 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72095075713155080-core_node6-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 25 /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> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52720
[junit4] 2> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52720
[junit4] 2> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52720
[junit4] 2> 1277886 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=90FE65378CC10EF8 -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=Indian/Mauritius -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 59.1s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([90FE65378CC10EF8:3E52D45D23C55CC]: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:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=958, maxMBSortInHeap=6.67083207805131, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7ff3ede7), locale=lt, timezone=Indian/Mauritius
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=115478896,total=492756992
[junit4] 2> NOTE: All tests run in this JVM: [TestSchemaManager, LeaderTragicEventTest, TestSort, FieldAnalysisRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, StatelessScriptUpdateProcessorFactoryTest, TestCustomStream, LeaderElectionContextKeyTest, TestJsonFacetRefinement, ZookeeperStatusHandlerTest, ZkStateReaderTest, HdfsBasicDistributedZkTest, SharedFSAutoReplicaFailoverTest, TestFieldCollectionResource, AssignBackwardCompatibilityTest, DateMathParserTest, TestManagedStopFilterFactory, TestLMDirichletSimilarityFactory, TestRequestStatusCollectionAPI, DistanceFunctionTest, TestCollectionAPI, TestPostingsSolrHighlighter, OverseerSolrResponseUnsafeSerializationTest, TestMergePolicyConfig, RegexBoostProcessorTest, SplitShardTest, CoreAdminOperationTest, TestPayloadCheckQParserPlugin, TestPolicyCloud, IndexSizeEstimatorTest, TestShardHandlerFactory, TestCloudDeleteByQuery, PeerSyncTest, TestSolrIndexConfig, ClassificationUpdateProcessorFactoryTest, SolrJmxReporterTest, HttpSolrCallGetCoreTest, TestSolrCachePerf, TestRTGBase, SpatialRPTFieldTypeTest, CachingDirectoryFactoryTest, ExternalFileFieldSortTest, TermVectorComponentTest, ResourceLoaderTest, HdfsRecoveryZkTest, IndexSizeTriggerTest, TestLeaderElectionWithEmptyReplica, HdfsUnloadDistributedZkTest, TestWriterPerf, TestTlogReplica, CollectionStateFormat2Test, TestSimClusterStateProvider, TestPhraseSuggestions, TestDistributedMissingSort, TestSimExtremeIndexing, TestSchemaResource, XsltUpdateRequestHandlerTest, UpdateRequestProcessorFactoryTest, TestDistributedMap, NumericFieldsTest, TestMinHashQParser, PrimitiveFieldTypeTest, TestUtils, TestWithCollection, TestPivotHelperCode, TestAuthorizationFramework, TestBinaryResponseWriter, QueryParsingTest, IgnoreLargeDocumentProcessorFactoryTest, CollectionTooManyReplicasTest, TestPackages, CdcrUpdateLogTest, TestJavabinTupleStreamParser, DistributedTermsComponentTest, SortByFunctionTest, TestSafeXMLParsing, SuggesterTSTTest, BooleanFieldTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSolrTestCaseJ4, SpatialHeatmapFacetsTest, TestSimPolicyCloud, ReplaceNodeNoTargetTest, WrapperMergePolicyFactoryTest, TestHdfsBackupRestoreCore, TestUseDocValuesAsStored2, ScheduledTriggerIntegrationTest]
[junit4] Completed [507/910 (1!)] on J0 in 59.21s, 1 test, 1 failure <<< FAILURES!
[...truncated 49174 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1166/consoleText
[repro] Revision: 5e7be63ca7fbd234526fceb2d7a0594a54f90670
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3486 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=90FE65378CC10EF8 -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=Indian/Mauritius -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]