You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/24 05:29:04 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-11.0.6) - Build # 1166
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1166/
Java: 64bit/jdk-11.0.6 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([90FE65378CC10EF8:3E52D45D23C55CC]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15147 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1218746 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1218746 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1218748 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\data-dir-112-001
[junit4] 2> 1218748 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 1218748 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1218750 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[90FE65378CC10EF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1218753 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1218754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001
[junit4] 2> 1218754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1218755 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1218755 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer start zk server on port: 52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52720
[junit4] 2> 1218899 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52720
[junit4] 2> 1218901 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218906 INFO (zkConnectionManagerCallback-9101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218906 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1218919 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218922 INFO (zkConnectionManagerCallback-9103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218922 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1218924 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1218926 INFO (zkConnectionManagerCallback-9105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1218926 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219079 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1219079 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1219079 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fa223b1{/solr,null,AVAILABLE}
[junit4] 2> 1219081 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a72d9a5{/solr,null,AVAILABLE}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37e690d1{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52733}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.e.j.s.Server Started @1182745ms
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52733}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@504bf840{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52734}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.e.j.s.Server Started @1182745ms
[junit4] 2> 1219084 ERROR (jetty-launcher-9106-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52734}
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:51.100036700Z
[junit4] 2> 1219084 ERROR (jetty-launcher-9106-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1219084 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:51.100751800Z
[junit4] 2> 1219085 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219085 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219088 INFO (zkConnectionManagerCallback-9110-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219088 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219088 INFO (zkConnectionManagerCallback-9108-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219088 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219089 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1219089 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1219434 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1219440 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@45320ac8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219444 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@45320ac8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@57c30fc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 WARN (jetty-launcher-9106-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@57c30fc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219449 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1219451 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219454 INFO (zkConnectionManagerCallback-9124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219454 INFO (jetty-launcher-9106-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219562 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1219567 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7e136e4d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219567 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7e136e4d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d64ef65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 WARN (jetty-launcher-9106-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d64ef65[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1219575 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1219579 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219581 INFO (zkConnectionManagerCallback-9132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219581 INFO (jetty-launcher-9106-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219600 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219604 INFO (zkConnectionManagerCallback-9134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219606 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219732 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52734_solr
[junit4] 2> 1219732 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.Overseer Overseer (id=72095075713155079-127.0.0.1:52734_solr-n_0000000000) starting
[junit4] 2> 1219742 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52734_solr
[junit4] 2> 1219742 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:52734_solr
[junit4] 2> 1219742 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1219744 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1219745 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1219746 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219746 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1219748 WARN (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1219753 INFO (zkConnectionManagerCallback-9143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219753 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219753 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:52734_solr]
[junit4] 2> 1219754 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1219755 DEBUG (ScheduledTrigger-9139-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1219755 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1219762 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1219768 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52733_solr as DOWN
[junit4] 2> 1219769 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1219769 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52733_solr
[junit4] 2> 1219771 INFO (zkCallback-9133-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1219771 INFO (zkCallback-9142-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1219772 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1219772 WARN (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1219774 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1219798 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1219801 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219805 INFO (jetty-launcher-9106-thread-2) [n:127.0.0.1:52734_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.
[junit4] 2> 1219817 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219824 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219824 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1219826 INFO (jetty-launcher-9106-thread-1) [n:127.0.0.1:52733_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.
[junit4] 2> 1219856 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1219856 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1219859 INFO (zkConnectionManagerCallback-9161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1219859 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1219861 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1219861 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52720/solr ready
[junit4] 2> 1219883 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1219902 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1219912 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1219916 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1219916 DEBUG (qtp1521718064-12883) [n:127.0.0.1:52734_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1219916 INFO (qtp1521718064-12883) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 1219916 DEBUG (zkCallback-9133-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1219919 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1219921 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1219922 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1219927 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1219931 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1219931 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1219934 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1219936 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1219938 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1219940 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1219941 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1219944 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1219946 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1219948 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1219950 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1219951 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1219953 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1219956 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1219957 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1219959 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1219961 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1219963 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1219965 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1219967 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1219969 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1219971 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1219973 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1219975 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1219977 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1219979 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1219981 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1219982 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1219990 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1219991 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1219993 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1219995 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1219995 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1219999 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1220000 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1220002 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1220004 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1220005 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1220007 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1220009 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1220011 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1220011 INFO (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1220161 WARN (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection testScheduledTrigger is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1220161 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1220166 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1220169 INFO (qtp1521718064-12881) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1220171 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1220174 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1220177 INFO (qtp1521718064-12879) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1220177 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 571925961 sessionWrapper.createTime 571925955735800, this.sessionWrapper.createTime 571925955735800
[junit4] 2> 1220182 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:52734/solr",
[junit4] 2> "node_name":"127.0.0.1:52734_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220182 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:52733/solr",
[junit4] 2> "node_name":"127.0.0.1:52733_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220188 INFO (OverseerStateUpdate-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:52733/solr",
[junit4] 2> "node_name":"127.0.0.1:52733_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1220478 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1220478 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1220488 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1220495 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220495 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220507 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1220517 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220521 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220543 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1220701 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1220719 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1220726 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1220749 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n4' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1220750 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220750 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n1' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52734.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220763 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n2' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52733.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ff3b180
[junit4] 2> 1220770 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1220870 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1220870 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1220874 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1220874 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220875 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1220876 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667546127156641792
[junit4] 2> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1220881 INFO (searcherExecutor-9163-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n4] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1220886 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0}, version=0}
[junit4] 2> 1220886 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1220886 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1220886 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1220892 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoute in=9999ms
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220892 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1220892 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220892 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220896 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1220897 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1220897 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667546127178661888
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1220897 INFO (searcherExecutor-9165-thread-1-processing-n:127.0.0.1:52734_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1220897 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667546127178661888
[junit4] 2> 1220903 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0}, version=1}
[junit4] 2> 1220903 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1220903 INFO (searcherExecutor-9167-thread-1-processing-n:127.0.0.1:52733_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1220906 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1220906 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1221193 DEBUG (ScheduledTrigger-9139-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1221611 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n4/
[junit4] 2> 1221612 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:52733/solr START replicas=[https://127.0.0.1:52734/solr/testScheduledTrigger_shard1_replica_n1/, https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n2/] nUpdates=100
[junit4] 2> 1221613 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n4 url=https://127.0.0.1:52733/solr DONE. We have no versions. sync failed.
[junit4] 2> 1221623 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1221623 INFO (qtp1521718064-12879) [n:127.0.0.1:52734_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1221623 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72095075713155080-core_node6-n_0000000000
[junit4] 2> 1221625 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:52733/solr/testScheduledTrigger_shard1_replica_n4/ shard1
[junit4] 2> 1221771 INFO (zkCallback-9142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1221773 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1221780 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=907
[junit4] 2> 1221919 INFO (zkCallback-9142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1221919 INFO (zkCallback-9142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1222346 INFO (qtp1025440673-12880) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1296
[junit4] 2> 1222347 INFO (qtp1521718064-12877) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1303
[junit4] 2> 1222347 DEBUG (OverseerThreadFactory-9141-thread-1-processing-n:127.0.0.1:52734_solr) [n:127.0.0.1:52734_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1222349 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1222491 INFO (zkCallback-9142-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1222491 INFO (zkCallback-9133-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1222491 INFO (zkCallback-9142-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1222492 INFO (zkCallback-9133-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1222492 INFO (qtp1025440673-12882) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=1788
[junit4] 2> 1222492 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1222497 INFO (qtp1025440673-12876) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n2:INDEX.sizeInBytes&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1222499 INFO (qtp1025440673-12878) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1222500 INFO (qtp1521718064-12881) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1222503 INFO (qtp1521718064-12883) [n:127.0.0.1:52734_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1222503 DEBUG (qtp1025440673-12951) [n:127.0.0.1:52733_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1222503 INFO (qtp1025440673-12951) [n:127.0.0.1:52733_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1222504 DEBUG (zkCallback-9133-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1222504 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1222505 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1222505 DEBUG (OverseerAutoScalingTriggerThread-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1222507 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1222508 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ea3fce2{/solr,null,AVAILABLE}
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@654e73d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:52831}
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.s.Server Started @1185134ms
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52831}
[junit4] 2> 1222511 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 1222511 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T04:51:54.527815200Z
[junit4] 2> 1222513 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1222515 INFO (zkConnectionManagerCallback-9181-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1222515 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1222516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1222630 DEBUG (ScheduledTrigger-9139-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1222802 INFO (OverseerCollectionConfigSetProcessor-72095075713155079-127.0.0.1:52734_solr-n_0000000000) [n:127.0.0.1:52734_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1222881 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1222884 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c8cff90[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222884 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c8cff90[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222889 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f1ad2aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222889 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f1ad2aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1222891 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52720/solr
[junit4] 2> 1222892 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1222895 INFO (zkConnectionManagerCallback-9192-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1222895 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223042 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223046 INFO (zkConnectionManagerCallback-9194-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223046 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223053 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1223059 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52831_solr as DOWN
[junit4] 2> 1223062 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1223062 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52831_solr
[junit4] 2> 1223064 INFO (zkCallback-9160-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9133-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9142-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (zkCallback-9193-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1223064 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1223064 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [n:127.0.0.1:52831_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1223108 INFO (TEST-ScheduledTriggerIntegrationTest.testS
[...truncated too long message...]
(jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1275642 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33ef8cc: rootName = solr_52733, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1803418e
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a30f091: rootName = solr_52733, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1394bf04
[junit4] 2> 1275643 INFO (jetty-closer-9203-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1275722 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@654e73d6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275723 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4ea3fce2{/solr,null,UNAVAILABLE}
[junit4] 2> 1275723 INFO (jetty-closer-9203-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275816 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@37e690d1{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275817 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1fa223b1{/solr,null,UNAVAILABLE}
[junit4] 2> 1275817 INFO (jetty-closer-9203-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275911 INFO (jetty-closer-9203-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72095075713155079-127.0.0.1:52734_solr-n_0000000000) closing
[junit4] 2> 1275912 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@504bf840{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 1275912 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@a72d9a5{/solr,null,UNAVAILABLE}
[junit4] 2> 1275913 INFO (jetty-closer-9203-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1275913 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1276104 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72095075713155080-core_node6-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52720
[junit4] 2> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52720
[junit4] 2> 1276111 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52720
[junit4] 2> 1277886 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[90FE65378CC10EF8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=90FE65378CC10EF8 -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=Indian/Mauritius -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 59.1s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([90FE65378CC10EF8:3E52D45D23C55CC]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_90FE65378CC10EF8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=958, maxMBSortInHeap=6.67083207805131, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7ff3ede7), locale=lt, timezone=Indian/Mauritius
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=115478896,total=492756992
[junit4] 2> NOTE: All tests run in this JVM: [TestSchemaManager, LeaderTragicEventTest, TestSort, FieldAnalysisRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, StatelessScriptUpdateProcessorFactoryTest, TestCustomStream, LeaderElectionContextKeyTest, TestJsonFacetRefinement, ZookeeperStatusHandlerTest, ZkStateReaderTest, HdfsBasicDistributedZkTest, SharedFSAutoReplicaFailoverTest, TestFieldCollectionResource, AssignBackwardCompatibilityTest, DateMathParserTest, TestManagedStopFilterFactory, TestLMDirichletSimilarityFactory, TestRequestStatusCollectionAPI, DistanceFunctionTest, TestCollectionAPI, TestPostingsSolrHighlighter, OverseerSolrResponseUnsafeSerializationTest, TestMergePolicyConfig, RegexBoostProcessorTest, SplitShardTest, CoreAdminOperationTest, TestPayloadCheckQParserPlugin, TestPolicyCloud, IndexSizeEstimatorTest, TestShardHandlerFactory, TestCloudDeleteByQuery, PeerSyncTest, TestSolrIndexConfig, ClassificationUpdateProcessorFactoryTest, SolrJmxReporterTest, HttpSolrCallGetCoreTest, TestSolrCachePerf, TestRTGBase, SpatialRPTFieldTypeTest, CachingDirectoryFactoryTest, ExternalFileFieldSortTest, TermVectorComponentTest, ResourceLoaderTest, HdfsRecoveryZkTest, IndexSizeTriggerTest, TestLeaderElectionWithEmptyReplica, HdfsUnloadDistributedZkTest, TestWriterPerf, TestTlogReplica, CollectionStateFormat2Test, TestSimClusterStateProvider, TestPhraseSuggestions, TestDistributedMissingSort, TestSimExtremeIndexing, TestSchemaResource, XsltUpdateRequestHandlerTest, UpdateRequestProcessorFactoryTest, TestDistributedMap, NumericFieldsTest, TestMinHashQParser, PrimitiveFieldTypeTest, TestUtils, TestWithCollection, TestPivotHelperCode, TestAuthorizationFramework, TestBinaryResponseWriter, QueryParsingTest, IgnoreLargeDocumentProcessorFactoryTest, CollectionTooManyReplicasTest, TestPackages, CdcrUpdateLogTest, TestJavabinTupleStreamParser, DistributedTermsComponentTest, SortByFunctionTest, TestSafeXMLParsing, SuggesterTSTTest, BooleanFieldTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSolrTestCaseJ4, SpatialHeatmapFacetsTest, TestSimPolicyCloud, ReplaceNodeNoTargetTest, WrapperMergePolicyFactoryTest, TestHdfsBackupRestoreCore, TestUseDocValuesAsStored2, ScheduledTriggerIntegrationTest]
[junit4] Completed [507/910 (1!)] on J0 in 59.21s, 1 test, 1 failure <<< FAILURES!
[...truncated 49174 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1166/consoleText
[repro] Revision: 5e7be63ca7fbd234526fceb2d7a0594a54f90670
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3486 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=90FE65378CC10EF8 -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=Indian/Mauritius -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]