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/10 11:30:45 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-14) - Build # 1105 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1105/
Java: 64bit/jdk-14 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([9867E6955558938B:B7CAEE70BA5C8BF]:0)
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertNotNull(Assert.java:712)
at org.junit.Assert.assertNotNull(Assert.java:722)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:126)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 13463 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 58245 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-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> 58248 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-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_9867E6955558938B-001\data-dir-14-001
[junit4] 2> 58248 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 58250 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 58257 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 58272 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[9867E6955558938B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 58276 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 58281 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] 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_9867E6955558938B-001\tempDir-001
[junit4] 2> 58288 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 58296 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 58296 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 58601 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer start zk server on port: 57537
[junit4] 2> 58601 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57537
[junit4] 2> 58601 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57537
[junit4] 2> 58603 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57537
[junit4] 2> 58670 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 58716 INFO (zkConnectionManagerCallback-207-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 58716 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 58750 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 58757 INFO (zkConnectionManagerCallback-209-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 58757 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 58759 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 58766 INFO (zkConnectionManagerCallback-211-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 58767 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59089 WARN (jetty-launcher-212-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 59089 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 59089 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 59089 WARN (jetty-launcher-212-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 59089 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 59089 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 59112 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 59117 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 59125 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 59125 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 59126 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 59126 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 59127 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 59127 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 59140 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d4d24b0{/solr,null,AVAILABLE}
[junit4] 2> 59140 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@674a7f01{/solr,null,AVAILABLE}
[junit4] 2> 59171 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b1d9796{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:57551}
[junit4] 2> 59171 INFO (jetty-launcher-212-thread-2) [ ] o.e.j.s.Server Started @59209ms
[junit4] 2> 59171 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57551}
[junit4] 2> 59189 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@774b5c83{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:57552}
[junit4] 2> 59189 INFO (jetty-launcher-212-thread-1) [ ] o.e.j.s.Server Started @59227ms
[junit4] 2> 59189 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57552}
[junit4] 2> 59194 ERROR (jetty-launcher-212-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 59194 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 59194 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 59194 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 59194 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 59194 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-10T10:36:58.133830800Z
[junit4] 2> 59196 ERROR (jetty-launcher-212-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 59196 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 59197 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 59197 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 59197 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 59197 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-10T10:36:58.136398500Z
[junit4] 2> 59197 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59198 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59246 INFO (zkConnectionManagerCallback-216-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59247 INFO (zkConnectionManagerCallback-214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59247 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59247 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59252 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 59252 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 59536 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 59540 WARN (jetty-launcher-212-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50cbb998[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59540 WARN (jetty-launcher-212-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50cbb998[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59553 WARN (jetty-launcher-212-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@16035b21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59553 WARN (jetty-launcher-212-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@16035b21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59554 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57537/solr
[junit4] 2> 59564 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59568 INFO (zkConnectionManagerCallback-230-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59568 INFO (jetty-launcher-212-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59579 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 59582 WARN (jetty-launcher-212-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@632e7d15[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59582 WARN (jetty-launcher-212-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@632e7d15[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59588 WARN (jetty-launcher-212-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39f430d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59588 WARN (jetty-launcher-212-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39f430d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 59589 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57537/solr
[junit4] 2> 59589 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59595 INFO (zkConnectionManagerCallback-238-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59595 INFO (jetty-launcher-212-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59675 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59679 INFO (zkConnectionManagerCallback-240-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59679 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59776 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 59784 INFO (zkConnectionManagerCallback-244-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 59784 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59857 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57552_solr
[junit4] 2> 59857 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.c.Overseer Overseer (id=72086345249325064-127.0.0.1:57552_solr-n_0000000000) starting
[junit4] 2> 59987 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57552_solr
[junit4] 2> 60021 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57552_solr
[junit4] 2> 60040 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 60076 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 60080 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 60088 WARN (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_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> 60099 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 60148 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 60184 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 60184 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 60195 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57552.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60195 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57552.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60195 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57552.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60198 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 60198 INFO (jetty-launcher-212-thread-1) [n:127.0.0.1:57552_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_9867E6955558938B-001\tempDir-001\node1\.
[junit4] 2> 60199 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:57552_solr]
[junit4] 2> 60200 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 60206 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 60206 DEBUG (ScheduledTrigger-249-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> 60240 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57551_solr as DOWN
[junit4] 2> 60246 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 60246 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57551_solr
[junit4] 2> 60277 INFO (zkCallback-243-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 60281 INFO (zkCallback-239-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 60283 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 60284 WARN (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_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> 60321 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 60409 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57551.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60409 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57551.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60411 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57551.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 60413 INFO (jetty-launcher-212-thread-2) [n:127.0.0.1:57551_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_9867E6955558938B-001\tempDir-001\node2\.
[junit4] 2> 60548 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 60550 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 60578 INFO (zkConnectionManagerCallback-267-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 60578 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 60581 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 60588 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57537/solr ready
[junit4] 2> 60834 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 60918 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=11
[junit4] 2> 60940 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 60944 DEBUG (qtp1689824845-265) [n:127.0.0.1:57552_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 60945 INFO (qtp1689824845-265) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=80
[junit4] 2> 60947 DEBUG (zkCallback-243-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 60948 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 60952 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 60952 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 60961 INFO (qtp665586603-273) [n:127.0.0.1:57551_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> 60972 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 60976 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 60979 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 60983 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 60985 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 60989 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 60996 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61004 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61007 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61009 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61012 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61015 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61017 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61026 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61031 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61033 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61036 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61036 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61040 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61040 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61044 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61047 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61050 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61052 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61055 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61057 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61060 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61062 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61064 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61067 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61070 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61072 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61075 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61078 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61081 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61084 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61087 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61091 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61093 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61097 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61100 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61105 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61107 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 61110 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 61116 INFO (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 61228 DEBUG (ScheduledTrigger-249-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 61232 WARN (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_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> 61240 DEBUG (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 61248 INFO (qtp665586603-259) [n:127.0.0.1:57551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2
[junit4] 2> 61256 INFO (qtp1689824845-271) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 61259 DEBUG (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 61269 INFO (qtp665586603-272) [n:127.0.0.1:57551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=6
[junit4] 2> 61274 INFO (qtp1689824845-266) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 61294 DEBUG (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 438711407 sessionWrapper.createTime 438711370964100, this.sessionWrapper.createTime 438711370964100
[junit4] 2> 61305 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_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:57552/solr",
[junit4] 2> "node_name":"127.0.0.1:57552_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 61309 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_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:57551/solr",
[junit4] 2> "node_name":"127.0.0.1:57551_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 61313 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_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:57551/solr",
[junit4] 2> "node_name":"127.0.0.1:57551_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 61520 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 61520 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 61521 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 61529 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 61558 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 61560 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 61560 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 61617 INFO (qtp665586603-259) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 61618 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 61618 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 61899 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 61899 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 61899 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 61977 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 61982 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57552.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 61983 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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_9867E6955558938B-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_9867E6955558938B-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 61985 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 61985 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 61985 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57551.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 61985 INFO (qtp665586603-259) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57551.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31502e8a
[junit4] 2> 61985 INFO (qtp665586603-263) [n:127.0.0.1:57551_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_9867E6955558938B-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_9867E6955558938B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 61985 INFO (qtp665586603-259) [n:127.0.0.1:57551_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_9867E6955558938B-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_9867E6955558938B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 62231 DEBUG (ScheduledTrigger-249-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 62353 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62353 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62363 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62363 INFO (qtp665586603-259) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 62365 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62365 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62376 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62376 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 62379 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62379 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62379 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62380 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62380 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62383 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62383 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 62383 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62383 INFO (qtp665586603-259) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 62388 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62388 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62388 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62389 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62389 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62390 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 62390 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62392 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62395 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62395 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62396 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62396 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62400 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62401 INFO (qtp1689824845-270) [n:127.0.0.1:57552_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 62401 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62401 INFO (qtp665586603-259) [n:127.0.0.1:57551_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 1666299484008611840
[junit4] 2> 62401 INFO (qtp665586603-263) [n:127.0.0.1:57551_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 1666299484008611840
[junit4] 2> 62402 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62402 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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 1666299484009660416
[junit4] 2> 62409 INFO (searcherExecutor-273-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 62409 INFO (searcherExecutor-273-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:57551_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> 62409 INFO (searcherExecutor-271-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 62409 INFO (searcherExecutor-271-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:57551_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> 62409 INFO (searcherExecutor-273-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:57551_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
[junit4] 2> 62409 INFO (searcherExecutor-271-thread-1-processing-n:127.0.0.1:57551_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:57551_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
[junit4] 2> 62409 INFO (searcherExecutor-269-thread-1-processing-n:127.0.0.1:57552_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:57552_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 62409 INFO (searcherExecutor-269-thread-1-processing-n:127.0.0.1:57552_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:57552_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> 62411 INFO (searcherExecutor-269-thread-1-processing-n:127.0.0.1:57552_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:57552_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
[junit4] 2> 62420 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62420 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62420 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62420 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62424 INFO (qtp665586603-259) [n:127.0.0.1:57551_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> 62424 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62424 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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> 62424 INFO (qtp665586603-263) [n:127.0.0.1:57551_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> 62460 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 62460 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 62460 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:57551/solr/testScheduledTrigger_shard1_replica_n4/
[junit4] 2> 62464 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:57551/solr START replicas=[https://127.0.0.1:57552/solr/testScheduledTrigger_shard1_replica_n1/, https://127.0.0.1:57551/solr/testScheduledTrigger_shard1_replica_n2/] nUpdates=100
[junit4] 2> 62464 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:57551/solr DONE. We have no versions. sync failed.
[junit4] 2> 62511 INFO (qtp665586603-272) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 62512 INFO (qtp1689824845-266) [n:127.0.0.1:57552_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=1
[junit4] 2> 62515 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 62515 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 62516 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72086345249325063-core_node6-n_0000000000
[junit4] 2> 62520 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:57551/solr/testScheduledTrigger_shard1_replica_n4/ shard1
[junit4] 2> 62628 INFO (zkCallback-239-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> 62628 INFO (zkCallback-239-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> 62632 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 62642 INFO (qtp665586603-263) [n:127.0.0.1:57551_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=1122
[junit4] 2> 62748 INFO (zkCallback-239-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> 62748 INFO (zkCallback-239-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> 62749 INFO (zkCallback-239-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> 63233 DEBUG (ScheduledTrigger-249-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 63463 INFO (qtp665586603-259) [n:127.0.0.1:57551_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=1933
[junit4] 2> 63463 INFO (qtp1689824845-270) [n:127.0.0.1:57552_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=1944
[junit4] 2> 63464 DEBUG (OverseerThreadFactory-251-thread-1-processing-n:127.0.0.1:57552_solr) [n:127.0.0.1:57552_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 63467 INFO (qtp665586603-273) [n:127.0.0.1:57551_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> 63562 INFO (zkCallback-243-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> 63562 INFO (zkCallback-243-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> 63562 INFO (zkCallback-239-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> 63562 INFO (zkCallback-239-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> 63562 INFO (zkCallback-239-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> 63563 INFO (qtp665586603-273) [n:127.0.0.1:57551_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=2601
[junit4] 2> 63564 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 63573 INFO (qtp665586603-342) [n:127.0.0.1:57551_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=1
[junit4] 2> 63578 INFO (qtp665586603-263) [n:127.0.0.1:57551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2
[junit4] 2> 63581 INFO (qtp1689824845-271) [n:127.0.0.1:57552_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=1
[junit4] 2> 63584 INFO (qtp1689824845-265) [n:127.0.0.1:57552_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 63585 DEBUG (qtp665586603-272) [n:127.0.0.1:57551_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 63586 INFO (qtp665586603-272) [n:127.0.0.1:57551_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=18
[junit4] 2> 63587 DEBUG (zkCallback-243-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 63587 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 63588 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 63588 DEBUG (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 63590 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 63590 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 63590 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 63590 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 63591 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 63591 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 63591 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 63591 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1bfbe070{/solr,null,AVAILABLE}
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1fcf8e09{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:57642}
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.s.Server Started @63632ms
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57642}
[junit4] 2> 63594 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 63594 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-10T10:37:02.533822Z
[junit4] 2> 63596 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 63599 INFO (zkConnectionManagerCallback-287-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63599 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 63599 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 63915 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 63922 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2b4df37d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 63922 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2b4df37d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 63928 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@654a380a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 63928 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@654a380a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 63930 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57537/solr
[junit4] 2> 63945 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 63949 INFO (zkConnectionManagerCallback-298-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 63949 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 64053 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 64056 INFO (zkConnectionManagerCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 64056 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 64063 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 64070 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57642_solr as DOWN
[junit4] 2> 64071 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 64071 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57642_solr
[junit4] 2> 64073 INFO (zkCallback-239-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 64073 INFO (zkCallback-243-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 64073 INFO (zkCallback-266-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 64073 INFO (zkCallback-299-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 64076 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 64077 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_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> 64102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [n:127.0.0.1:57642_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
[...truncated too long message...]
Overseer Overseer (id=72086345249325064-127.0.0.1:57552_solr-n_0000000000) closing
[junit4] 2> 67499 INFO (OverseerStateUpdate-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [n:127.0.0.1:57552_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57552_solr
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 67499 INFO (OverseerAutoScalingTriggerThread-72086345249325064-127.0.0.1:57552_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 67499 DEBUG (closeThreadPool-324-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 67504 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 67504 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@553c3957: rootName = solr_57551, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@26a7c098
[junit4] 2> 67508 INFO (closeThreadPool-324-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72086345249325064-127.0.0.1:57552_solr-n_0000000000) closing
[junit4] 2> 67511 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 67511 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@706eb587: rootName = solr_57551, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@7ff962ba
[junit4] 2> 67512 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 67512 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1988cea6: rootName = solr_57551, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@15c0942f
[junit4] 2> 67512 INFO (jetty-closer-309-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 67568 INFO (jetty-closer-309-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1fcf8e09{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 67573 INFO (jetty-closer-309-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1bfbe070{/solr,null,UNAVAILABLE}
[junit4] 2> 67573 INFO (jetty-closer-309-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 67601 INFO (jetty-closer-309-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72086345249325064-127.0.0.1:57552_solr-n_0000000000) closing
[junit4] 2> 67605 INFO (zkCallback-239-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 67607 INFO (jetty-closer-309-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@774b5c83{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 67607 INFO (jetty-closer-309-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@674a7f01{/solr,null,UNAVAILABLE}
[junit4] 2> 67608 INFO (jetty-closer-309-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 67715 INFO (jetty-closer-309-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@b1d9796{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 67716 INFO (jetty-closer-309-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1d4d24b0{/solr,null,UNAVAILABLE}
[junit4] 2> 67716 INFO (jetty-closer-309-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 67717 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 67930 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/72086345249325063-core_node6-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/sched_trigger_integration1
[junit4] 2>
[junit4] 2> 67931 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:57537
[junit4] 2> 67931 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57537
[junit4] 2> 67931 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57537
[junit4] 2> 69948 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[9867E6955558938B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=9867E6955558938B -Dtests.slow=true -Dtests.locale=fr-BF -Dtests.timezone=Australia/ACT -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 11.7s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([9867E6955558938B:B7CAEE70BA5C8BF]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:126)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_9867E6955558938B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=116, maxMBSortInHeap=5.681112663441319, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@b8122c), locale=fr-BF, timezone=Australia/ACT
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=30698936,total=194707456
[junit4] 2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest, TestBadConfig, TestMissingGroups, ScheduledTriggerIntegrationTest]
[junit4] Completed [23/908 (1!)] on J0 in 11.86s, 1 test, 1 failure <<< FAILURES!
[...truncated 47885 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1105/consoleText
[repro] Revision: 67be31cdcf147630835ac9346ee72e934b124f39
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3484 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=9867E6955558938B -Dtests.slow=true -Dtests.locale=fr-BF -Dtests.timezone=Australia/ACT -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...]