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/21 03:28:46 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk1.8.0_201) - Build #
1153 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1153/
Java: 64bit/jdk1.8.0_201 -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([439BACED019AFDEB:D080E49F5F67A6DF]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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 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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15452 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1643936 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-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> 1643937 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-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_439BACED019AFDEB-001\data-dir-98-001
[junit4] 2> 1643937 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=63 numCloses=63
[junit4] 2> 1643937 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1643941 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1643941 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439BACED019AFDEB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1643942 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1643944 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] 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_439BACED019AFDEB-001\tempDir-001
[junit4] 2> 1643944 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1643944 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1643944 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1644031 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer start zk server on port: 51191
[junit4] 2> 1644031 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:51191
[junit4] 2> 1644031 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51191
[junit4] 2> 1644031 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 51191
[junit4] 2> 1644032 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644035 INFO (zkConnectionManagerCallback-13272-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644035 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644037 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644038 INFO (zkConnectionManagerCallback-13274-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644038 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644039 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644040 INFO (zkConnectionManagerCallback-13276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644040 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644133 WARN (jetty-launcher-13277-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1644133 WARN (jetty-launcher-13277-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1644133 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1644134 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1644134 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1644134 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1644140 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1644140 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1644140 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1644140 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c04d40e{/solr,null,AVAILABLE}
[junit4] 2> 1644140 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1edc9371{/solr,null,AVAILABLE}
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60322662{SSL, (ssl, http/1.1)}{127.0.0.1:51204}
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.e.j.s.Server Started @1596905ms
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@468c8259{SSL, (ssl, http/1.1)}{127.0.0.1:51205}
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51204}
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.e.j.s.Server Started @1596905ms
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51205}
[junit4] 2> 1644141 ERROR (jetty-launcher-13277-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1644141 ERROR (jetty-launcher-13277-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T02:53:57.789Z
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1644141 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T02:53:57.789Z
[junit4] 2> 1644142 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644142 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644144 INFO (zkConnectionManagerCallback-13281-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644144 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644144 INFO (zkConnectionManagerCallback-13279-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644144 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644144 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1644144 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1644414 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1644414 WARN (jetty-launcher-13277-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1644415 WARN (jetty-launcher-13277-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b233a60[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644415 WARN (jetty-launcher-13277-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b233a60[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644418 WARN (jetty-launcher-13277-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1644419 WARN (jetty-launcher-13277-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@cb479de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644419 WARN (jetty-launcher-13277-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@cb479de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644419 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51191/solr
[junit4] 2> 1644420 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644422 INFO (zkConnectionManagerCallback-13295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644422 INFO (jetty-launcher-13277-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644505 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1644505 WARN (jetty-launcher-13277-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1644507 WARN (jetty-launcher-13277-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4aa7ab0c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644507 WARN (jetty-launcher-13277-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4aa7ab0c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644508 WARN (jetty-launcher-13277-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1644509 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644509 WARN (jetty-launcher-13277-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@757cbd6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644509 WARN (jetty-launcher-13277-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@757cbd6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1644510 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51191/solr
[junit4] 2> 1644510 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644510 INFO (zkConnectionManagerCallback-13302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644510 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644512 INFO (zkConnectionManagerCallback-13305-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644512 INFO (jetty-launcher-13277-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644560 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51205_solr
[junit4] 2> 1644560 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.c.Overseer Overseer (id=72078221006798854-127.0.0.1:51205_solr-n_0000000000) starting
[junit4] 2> 1644563 INFO (OverseerStateUpdate-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:51205_solr
[junit4] 2> 1644563 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51205_solr
[junit4] 2> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1644563 INFO (OverseerStateUpdate-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1644563 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1644563 WARN (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_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> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:51205_solr]
[junit4] 2> 1644563 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1644567 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1644567 DEBUG (ScheduledTrigger-13310-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> 1644600 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1644600 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644602 INFO (zkConnectionManagerCallback-13318-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644602 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644604 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1644607 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:51204_solr as DOWN
[junit4] 2> 1644608 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1644608 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51204_solr
[junit4] 2> 1644608 INFO (zkCallback-13301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1644608 INFO (zkCallback-13316-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1644608 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1644608 WARN (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_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> 1644649 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51205.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644653 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51205.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644653 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51205.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644655 INFO (jetty-launcher-13277-thread-1) [n:127.0.0.1:51205_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_439BACED019AFDEB-001\tempDir-001\node1\.
[junit4] 2> 1644657 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1644683 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51204.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644686 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51204.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644686 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51204.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1644688 INFO (jetty-launcher-13277-thread-2) [n:127.0.0.1:51204_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_439BACED019AFDEB-001\tempDir-001\node2\.
[junit4] 2> 1644707 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1644708 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1644709 INFO (zkConnectionManagerCallback-13332-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1644709 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1644710 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1644711 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51191/solr ready
[junit4] 2> 1644726 INFO (qtp1282730281-21382) [n:127.0.0.1:51205_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 1644740 INFO (qtp1791642898-21377) [n:127.0.0.1:51204_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> 1644742 INFO (qtp1282730281-21381) [n:127.0.0.1:51205_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1644744 INFO (qtp1282730281-21381) [n:127.0.0.1:51205_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> 1644745 DEBUG (qtp1791642898-21375) [n:127.0.0.1:51204_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1644745 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 1644746 DEBUG (zkCallback-13301-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1644746 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1644748 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1644748 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1644748 INFO (qtp1791642898-21377) [n:127.0.0.1:51204_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> 1644750 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1644750 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1644752 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1644754 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644755 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644756 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644757 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644758 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644759 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644760 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644762 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644763 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644764 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644765 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644767 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644768 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644769 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644771 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644771 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644773 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644774 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644776 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644777 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644778 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644779 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644781 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644782 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644783 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644784 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644785 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644787 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644788 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644789 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644790 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644790 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644793 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644793 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644796 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644796 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644798 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644800 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644802 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644803 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1644804 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1644805 INFO (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1644895 WARN (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_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> 1644896 DEBUG (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1644899 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1644901 INFO (qtp1282730281-21382) [n:127.0.0.1:51205_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> 1644902 DEBUG (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1644904 INFO (qtp1791642898-21378) [n:127.0.0.1:51204_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> 1644906 INFO (qtp1282730281-21379) [n:127.0.0.1:51205_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> 1644907 DEBUG (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 314743683 sessionWrapper.createTime 314743677612700, this.sessionWrapper.createTime 314743677612700
[junit4] 2> 1644909 INFO (OverseerStateUpdate-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_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:51205/solr",
[junit4] 2> "node_name":"127.0.0.1:51205_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1644911 INFO (OverseerStateUpdate-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_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:51204/solr",
[junit4] 2> "node_name":"127.0.0.1:51204_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1644912 INFO (OverseerStateUpdate-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_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:51204/solr",
[junit4] 2> "node_name":"127.0.0.1:51204_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1645085 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645085 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645087 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645108 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645108 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645108 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645130 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1645135 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1645135 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1645255 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645259 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645260 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645281 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645281 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51204.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1645281 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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_439BACED019AFDEB-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439BACED019AFDEB-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1645284 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645284 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51205.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1645284 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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_439BACED019AFDEB-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439BACED019AFDEB-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1645285 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645286 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_51204.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c2fcca2
[junit4] 2> 1645286 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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_439BACED019AFDEB-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439BACED019AFDEB-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1645376 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645377 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645377 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645377 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645377 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645377 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645379 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645379 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645379 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1645379 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1645379 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645379 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1645380 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645380 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645380 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645381 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645381 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1645381 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1645381 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645381 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645381 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645382 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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 1667266918775521280
[junit4] 2> 1645382 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645382 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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 1667266918775521280
[junit4] 2> 1645382 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645382 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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 1667266918775521280
[junit4] 2> 1645383 INFO (searcherExecutor-13338-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1645383 INFO (searcherExecutor-13338-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:51204_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> 1645383 INFO (searcherExecutor-13334-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1645383 INFO (searcherExecutor-13334-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:51204_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> 1645383 INFO (searcherExecutor-13336-thread-1-processing-n:127.0.0.1:51205_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1645383 INFO (searcherExecutor-13336-thread-1-processing-n:127.0.0.1:51205_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:51205_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> 1645384 INFO (searcherExecutor-13338-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:51204_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> 1645384 INFO (searcherExecutor-13334-thread-1-processing-n:127.0.0.1:51204_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:51204_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> 1645384 INFO (searcherExecutor-13336-thread-1-processing-n:127.0.0.1:51205_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:51205_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> 1645386 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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_node5=0}, version=0}
[junit4] 2> 1645386 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1645386 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1645386 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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_node5=0}, version=1}
[junit4] 2> 1645386 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1645386 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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, core_node5=0}, version=2}
[junit4] 2> 1645386 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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> 1645390 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1645390 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1645390 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:51204/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 1645390 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:51204/solr START replicas=[https://127.0.0.1:51205/solr/testScheduledTrigger_shard1_replica_n1/, https://127.0.0.1:51204/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1645390 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:51204/solr DONE. We have no versions. sync failed.
[junit4] 2> 1645392 INFO (qtp1282730281-21381) [n:127.0.0.1:51205_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> 1645392 INFO (qtp1791642898-21378) [n:127.0.0.1:51204_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> 1645392 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1645392 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 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> 1645392 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72078221006798856-core_node5-n_0000000000
[junit4] 2> 1645393 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:51204/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 1645439 DEBUG (ScheduledTrigger-13310-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> 1645489 INFO (zkCallback-13316-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> 1645489 INFO (zkCallback-13316-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> 1645489 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1645493 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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=458
[junit4] 2> 1645586 INFO (zkCallback-13316-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> 1645586 INFO (zkCallback-13316-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> 1645586 INFO (zkCallback-13316-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1646340 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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=1355
[junit4] 2> 1646341 INFO (qtp1282730281-21372) [n:127.0.0.1:51205_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=1355
[junit4] 2> 1646341 DEBUG (OverseerThreadFactory-13312-thread-1-processing-n:127.0.0.1:51205_solr) [n:127.0.0.1:51205_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1646342 INFO (qtp1791642898-21377) [n:127.0.0.1:51204_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> 1646386 DEBUG (ScheduledTrigger-13310-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> 1646433 INFO (zkCallback-13301-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> 1646433 INFO (zkCallback-13301-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> 1646433 INFO (zkCallback-13316-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1646433 INFO (zkCallback-13316-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> 1646433 INFO (zkCallback-13316-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> 1646433 INFO (qtp1791642898-21377) [n:127.0.0.1:51204_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=1845
[junit4] 2> 1646433 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1646436 INFO (qtp1791642898-21375) [n:127.0.0.1:51204_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> 1646437 INFO (qtp1791642898-21376) [n:127.0.0.1:51204_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> 1646438 INFO (qtp1282730281-21382) [n:127.0.0.1:51205_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> 1646439 INFO (qtp1282730281-21379) [n:127.0.0.1:51205_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> 1646439 DEBUG (qtp1791642898-21378) [n:127.0.0.1:51204_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1646440 INFO (qtp1791642898-21378) [n:127.0.0.1:51204_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1646440 DEBUG (zkCallback-13301-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1646440 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1646440 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1646440 DEBUG (OverseerAutoScalingTriggerThread-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1646442 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1646442 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@631c217e{/solr,null,AVAILABLE}
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@365a86fd{SSL, (ssl, http/1.1)}{127.0.0.1:51262}
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.s.Server Started @1599468ms
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51262}
[junit4] 2> 1646444 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1646444 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T02:54:00.092Z
[junit4] 2> 1646445 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1646446 INFO (zkConnectionManagerCallback-13352-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1646446 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1646447 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1646582 INFO (OverseerCollectionConfigSetProcessor-72078221006798854-127.0.0.1:51205_solr-n_0000000000) [n:127.0.0.1:51205_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> 1646608 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1646609 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1646610 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1f6925dc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1646610 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1f6925dc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1646613 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 1646615 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@276818e1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1646615 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@276818e1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1646615 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51191/solr
[junit4] 2> 1646616 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1646617 INFO (zkConnectionManagerCallback-13363-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1646617 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1646714 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [n:127.0.0.1:51262_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1646715 INFO (zkConnectionManagerCallback-13365-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1646715
[...truncated too long message...]
hale.metrics.MetricRegistry@43ea5136
[junit4] 2> 1698541 INFO (jetty-closer-13374-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1698541 INFO (jetty-closer-13374-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@57f230aa: rootName = solr_51204, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@4ab4283
[junit4] 2> 1698543 INFO (jetty-closer-13374-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1698543 INFO (jetty-closer-13374-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10752e10: rootName = solr_51204, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@461827f8
[junit4] 2> 1698543 INFO (jetty-closer-13374-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1698662 INFO (jetty-closer-13374-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@365a86fd{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1698662 INFO (jetty-closer-13374-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@631c217e{/solr,null,UNAVAILABLE}
[junit4] 2> 1698663 INFO (jetty-closer-13374-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1698806 INFO (jetty-closer-13374-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@60322662{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1698807 INFO (jetty-closer-13374-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c04d40e{/solr,null,UNAVAILABLE}
[junit4] 2> 1698807 INFO (jetty-closer-13374-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1698944 INFO (jetty-closer-13374-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72078221006798854-127.0.0.1:51205_solr-n_0000000000) closing
[junit4] 2> 1698946 INFO (jetty-closer-13374-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@468c8259{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 1698946 INFO (jetty-closer-13374-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1edc9371{/solr,null,UNAVAILABLE}
[junit4] 2> 1698946 INFO (jetty-closer-13374-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1698947 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1699226 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> 6 /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> 24 /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/72078221006798856-core_node5-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /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> 1699234 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:51191
[junit4] 2> 1699234 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51191
[junit4] 2> 1699234 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 51191
[junit4] 2> 1702008 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439BACED019AFDEB]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=439BACED019AFDEB -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Europe/Luxembourg -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 58.1s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([439BACED019AFDEB:D080E49F5F67A6DF]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[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_439BACED019AFDEB-001
[junit4] 2> NOTE: test params are: codec=Lucene84, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4797f38d), locale=zh, timezone=Europe/Luxembourg
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=94953056,total=514850816
[junit4] 2> NOTE: All tests run in this JVM: [TestRequestStatusCollectionAPI, DateRangeFieldTest, TestClassicSimilarityFactory, TestPivotHelperCode, TestSolrIndexConfig, TestCustomStream, TestExternalRoleRuleBasedAuthorizationPlugin, TestMergePolicyConfig, BlockCacheTest, ExecutePlanActionTest, SparseHLLTest, CollectionStateFormat2Test, DistributedFacetPivotLongTailTest, CachingDirectoryFactoryTest, TestFoldingMultitermQuery, SplitShardTest, TestWithCollection, TestWriterPerf, TestLFUCache, MultiSolrCloudTestCaseTest, TestRandomRequestDistribution, DistributedTermsComponentTest, CreateCollectionCleanupTest, TestJettySolrRunner, TestFileDictionaryLookup, FastVectorHighlighterTest, DistributedIntervalFacetingTest, IndexSizeTriggerTest, HdfsBasicDistributedZkTest, TestMissingGroups, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestCloudDeleteByQuery, TestSimpleQParserPlugin, TestConfigSetProperties, CustomCollectionTest, IndexSizeEstimatorTest, ZkDynamicConfigTest, TestSolrTestCaseJ4, TestHdfsBackupRestoreCore, TestConfigSetsAPIExclusivity, TestRestoreCore, TimeZoneUtilsTest, BJQFilterAccessibleTest, TestTlogReplica, HdfsRecoveryZkTest, TestSchemaResource, CollectionTooManyReplicasTest, TestInPlaceUpdatesRequiredField, TestCloudJSONFacetJoinDomain, TestDistributedMissingSort, TestPolicyCloud, TestSimClusterStateProvider, TestDocTermOrds, SpellCheckComponentTest, TestShortCircuitedRequests, TestJoin, TestExactStatsCache, SpatialFilterTest, TestStandardQParsers, TestCloudNestedDocsSort, ForceLeaderWithTlogReplicasTest, OverseerSolrResponseUnsafeSerializationTest, BaseCdcrDistributedZkTest, TestSolrCloudWithHadoopAuthPlugin, ResourceLoaderTest, TestJsonFacets, PrimUtilsTest, ShowFileRequestHandlerTest, LeaderElectionContextKeyTest, TestAtomicUpdateErrorCases, TestSolrCloudWithSecureImpersonation, SoftAutoCommitTest, TestShardHandlerFactory, TestStressThreadBackup, ReplaceNodeNoTargetTest, WrapperMergePolicyFactoryTest, ZkShardTermsTest, TestJavabinTupleStreamParser, TolerantUpdateProcessorTest, AnalysisAfterCoreReloadTest, PeerSyncWithBufferUpdatesTest, TemplateUpdateProcessorTest, BooleanFieldTest, TestSchemaManager, ExplicitHLLTest, DistributedQueryComponentCustomSortTest, MetricsHandlerTest, TestLegacyNumericUtils, ChaosMonkeyNothingIsSafeTest, DistributedQueueTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest, OverseerRolesTest, RecoveryZkTest, RollingRestartTest, TestCloudPseudoReturnFields, TestCloudRecovery, TestDownShardTolerantSearch, TestLeaderElectionWithEmptyReplica, TestQueryingOnDownCollection, TestRandomFlRTGCloud, TestSegmentSorting, ZkNodePropsTest, ConcurrentDeleteAndCreateCollectionTest, TestCollectionAPI, ScheduledTriggerIntegrationTest]
[junit4] Completed [569/909 (1!)] on J0 in 58.41s, 1 test, 1 failure <<< FAILURES!
[...truncated 53564 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1153/consoleText
[repro] Revision: 6755796ddf76bc25d61e6bb3988924ac8a0071ec
[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 3479 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=439BACED019AFDEB -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Europe/Luxembourg -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...]