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/04/20 19:25:05 UTC
[JENKINS] Lucene-Solr-8.x-Windows (64bit/jdk-14) - Build # 1010 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1010/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([439F8C5D7FA24CCE:D084C42F215F17FA]:0)
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertNotNull(Assert.java:712)
at org.junit.Assert.assertNotNull(Assert.java:722)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:126)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 14462 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 761277 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-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> 761278 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-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_439F8C5D7FA24CCE-001\data-dir-84-001
[junit4] 2> 761278 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21
[junit4] 2> 761278 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 761280 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 761280 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[439F8C5D7FA24CCE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 761281 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 761281 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] 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_439F8C5D7FA24CCE-001\tempDir-001
[junit4] 2> 761282 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 761282 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 761283 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 761383 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer start zk server on port:53118
[junit4] 2> 761383 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:53118
[junit4] 2> 761383 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53118
[junit4] 2> 761383 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53118
[junit4] 2> 761385 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761388 INFO (zkConnectionManagerCallback-6412-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761388 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761392 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761394 INFO (zkConnectionManagerCallback-6414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761394 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761394 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761396 INFO (zkConnectionManagerCallback-6416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761396 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761501 WARN (jetty-launcher-6417-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 761501 WARN (jetty-launcher-6417-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 761501 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@87abfff{/solr,null,AVAILABLE}
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3737c28b{/solr,null,AVAILABLE}
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1d2575a4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53136}
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.e.j.s.Server Started @761538ms
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53136}
[junit4] 2> 761502 ERROR (jetty-launcher-6417-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 761502 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-20T18:49:59.833406300Z
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@15073342{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53135}
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.e.j.s.Server Started @761539ms
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53135}
[junit4] 2> 761506 ERROR (jetty-launcher-6417-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-20T18:49:59.837188Z
[junit4] 2> 761506 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761507 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761509 INFO (zkConnectionManagerCallback-6419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761509 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761509 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 761509 INFO (zkConnectionManagerCallback-6421-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761509 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761510 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 761822 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 761826 WARN (jetty-launcher-6417-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4d661e21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 761826 WARN (jetty-launcher-6417-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4d661e21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 761828 WARN (jetty-launcher-6417-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@489f04bb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 761828 WARN (jetty-launcher-6417-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@489f04bb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 761828 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53118/solr
[junit4] 2> 761828 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761828 INFO (zkConnectionManagerCallback-6435-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761828 INFO (jetty-launcher-6417-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 761940 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 761947 INFO (zkConnectionManagerCallback-6437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 761947 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 762082 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 762090 WARN (jetty-launcher-6417-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58a57f72[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 762090 WARN (jetty-launcher-6417-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58a57f72[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 762094 WARN (jetty-launcher-6417-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@419d4430[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 762094 WARN (jetty-launcher-6417-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@419d4430[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 762095 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53118/solr
[junit4] 2> 762095 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 762098 INFO (zkConnectionManagerCallback-6447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 762098 INFO (jetty-launcher-6417-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 762134 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53136_solr
[junit4] 2> 762134 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.c.Overseer Overseer (id=72081500221669382-127.0.0.1:53136_solr-n_0000000000) starting
[junit4] 2> 762142 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53136_solr
[junit4] 2> 762142 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:53136_solr
[junit4] 2> 762142 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 762142 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 762142 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 762142 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 762142 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 762142 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 762142 WARN (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_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> 762146 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:53136_solr]
[junit4] 2> 762148 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 762148 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 762148 DEBUG (ScheduledTrigger-6450-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 762170 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 762191 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53136.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762198 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53136.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762198 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53136.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762198 INFO (jetty-launcher-6417-thread-2) [n:127.0.0.1:53136_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_439F8C5D7FA24CCE-001\tempDir-001\node2\.
[junit4] 2> 762205 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 762214 INFO (zkConnectionManagerCallback-6459-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 762214 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 762221 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 762234 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:53135_solr as DOWN
[junit4] 2> 762234 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 762234 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53135_solr
[junit4] 2> 762238 INFO (zkCallback-6436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 762238 INFO (zkCallback-6458-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 762248 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 762248 WARN (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_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> 762273 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 762300 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53135.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762306 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53135.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762306 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53135.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 762306 INFO (jetty-launcher-6417-thread-1) [n:127.0.0.1:53135_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_439F8C5D7FA24CCE-001\tempDir-001\node1\.
[junit4] 2> 762338 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 762338 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 762358 INFO (zkConnectionManagerCallback-6472-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 762358 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 762359 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 762378 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53118/solr ready
[junit4] 2> 762398 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 762470 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=54
[junit4] 2> 762494 INFO (qtp333613480-9500) [n:127.0.0.1:53136_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 762494 DEBUG (qtp465620554-9503) [n:127.0.0.1:53135_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 762495 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=96
[junit4] 2> 762498 INFO (qtp465620554-9505) [n:127.0.0.1:53135_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> 762500 DEBUG (zkCallback-6436-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 762503 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 762512 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 762512 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 762534 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 762536 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 762539 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 762540 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762542 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762555 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762558 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762566 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762574 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762578 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762582 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762585 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762588 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762590 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762592 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762594 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762594 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762606 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762613 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762615 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762617 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762618 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762620 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762622 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762624 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762625 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762628 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762630 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762633 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762635 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762635 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762638 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762651 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762654 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762655 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762661 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762667 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762683 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762698 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762698 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762702 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762702 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762705 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 762706 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 762707 INFO (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 762814 WARN (OverseerThreadFactory-6452-thread-1-processing-n:127.0.0.1:53136_solr) [n:127.0.0.1:53136_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> 762823 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53135/solr",
[junit4] 2> "node_name":"127.0.0.1:53135_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 762826 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53136/solr",
[junit4] 2> "node_name":"127.0.0.1:53136_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 762830 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53135/solr",
[junit4] 2> "node_name":"127.0.0.1:53135_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 763041 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763042 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763042 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763042 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 763064 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763064 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763064 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763087 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 763088 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 763088 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 763183 DEBUG (ScheduledTrigger-6450-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 763411 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763411 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763414 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763471 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763472 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53136.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 763472 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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_439F8C5D7FA24CCE-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439F8C5D7FA24CCE-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 763475 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763476 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53135.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 763476 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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_439F8C5D7FA24CCE-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_439F8C5D7FA24CCE-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 763478 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763479 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53135.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7aaf7c02
[junit4] 2> 763479 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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_439F8C5D7FA24CCE-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439F8C5D7FA24CCE-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 763627 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763627 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763630 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763630 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763633 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763633 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 763634 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763634 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 763639 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11c52dc8[testScheduledTrigger_shard1_replica_n2] main]
[junit4] 2> 763642 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763642 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c05ce92[testScheduledTrigger_shard1_replica_n4] main]
[junit4] 2> 763642 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763642 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763647 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763647 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763647 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 763647 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763647 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 763647 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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 1664518562227683328
[junit4] 2> 763650 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763650 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763654 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763654 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 763654 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763656 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 763656 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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 1664518562237120512
[junit4] 2> 763656 INFO (searcherExecutor-6474-thread-1-processing-n:127.0.0.1:53136_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@11c52dc8[testScheduledTrigger_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763656 INFO (searcherExecutor-6474-thread-1-processing-n:127.0.0.1:53136_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 763656 INFO (searcherExecutor-6474-thread-1-processing-n:127.0.0.1:53136_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:53136_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> 763658 INFO (searcherExecutor-6474-thread-1-processing-n:127.0.0.1:53136_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:53136_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 Searcher@11c52dc8[testScheduledTrigger_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763658 INFO (searcherExecutor-6476-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5c05ce92[testScheduledTrigger_shard1_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763658 INFO (searcherExecutor-6476-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 763658 INFO (searcherExecutor-6476-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:53135_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> 763658 INFO (searcherExecutor-6476-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:53135_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 Searcher@5c05ce92[testScheduledTrigger_shard1_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763662 INFO (qtp333613480-9506) [n:127.0.0.1:53136_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 763662 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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> 763666 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 763666 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763666 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763666 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node5=0}, version=1}
[junit4] 2> 763666 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 763668 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763668 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 763668 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@60ac3f84[testScheduledTrigger_shard1_replica_n1] main]
[junit4] 2> 763672 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763672 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763672 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763674 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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=2 timeoutin=9999ms
[junit4] 2> 763674 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763674 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 763674 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 763675 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 763675 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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 1664518562257043456
[junit4] 2> 763675 INFO (searcherExecutor-6478-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@60ac3f84[testScheduledTrigger_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763675 INFO (searcherExecutor-6478-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 763675 INFO (searcherExecutor-6478-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:53135_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> 763675 INFO (searcherExecutor-6478-thread-1-processing-n:127.0.0.1:53135_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:53135_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 Searcher@60ac3f84[testScheduledTrigger_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 763679 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 763679 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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> 764180 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 764180 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 764180 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53135/solr/testScheduledTrigger_shard1_replica_n4/
[junit4] 2> 764180 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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=http://127.0.0.1:53135/solr START replicas=[http://127.0.0.1:53135/solr/testScheduledTrigger_shard1_replica_n1/, http://127.0.0.1:53136/solr/testScheduledTrigger_shard1_replica_n2/] nUpdates=100
[junit4] 2> 764180 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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=http://127.0.0.1:53135/solr DONE. We have no versions. sync failed.
[junit4] 2> 764182 INFO (qtp465620554-9501) [n:127.0.0.1:53135_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> 764184 INFO (qtp333613480-9502) [n:127.0.0.1:53136_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> 764185 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 764185 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 764185 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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/72081500221669384-core_node6-n_0000000000
[junit4] 2> 764190 INFO (qtp465620554-9503) [n:127.0.0.1:53135_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:53135/solr/testScheduledTrigger_shard1_replica_n4/ shard1
[junit4] 2> 764294 INFO (zkCallback-6458-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> 764294 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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> 764300 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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=1259
[junit4] 2> 764301 DEBUG (ScheduledTrigger-6450-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 764398 INFO (zkCallback-6458-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> 764398 INFO (zkCallback-6458-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> 764682 INFO (qtp333613480-9506) [n:127.0.0.1:53136_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=1641
[junit4] 2> 764743 INFO (qtp465620554-9507) [n:127.0.0.1:53135_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=1702
[junit4] 2> 764763 INFO (qtp465620554-9505) [n:127.0.0.1:53135_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> 764820 INFO (zkCallback-6458-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> 764820 INFO (zkCallback-6458-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> 764821 INFO (zkCallback-6436-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> 764821 INFO (zkCallback-6436-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> 764821 INFO (qtp465620554-9505) [n:127.0.0.1:53135_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=2323
[junit4] 2> 764821 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 764826 INFO (qtp465620554-9503) [n:127.0.0.1:53135_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&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 764827 INFO (qtp465620554-9507) [n:127.0.0.1:53135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2
[junit4] 2> 764831 INFO (qtp333613480-9500) [n:127.0.0.1:53136_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} status=0 QTime=1
[junit4] 2> 764834 INFO (qtp333613480-9500) [n:127.0.0.1:53136_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 764834 DEBUG (qtp465620554-9501) [n:127.0.0.1:53135_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 764834 INFO (qtp465620554-9501) [n:127.0.0.1:53135_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 764837 DEBUG (zkCallback-6436-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 764837 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 764837 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 764837 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@625e90d2{/solr,null,AVAILABLE}
[junit4] 2> 764837 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 764837 DEBUG (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@628eb77e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53220}
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.s.Server Started @764875ms
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53220}
[junit4] 2> 764842 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-20T18:50:03.173122600Z
[junit4] 2> 764842 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764845 INFO (zkConnectionManagerCallback-6492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764846 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764847 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 765107 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 765107 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@57adb763[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 765107 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@57adb763[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 765114 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@265590bd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 765114 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@265590bd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 765114 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53118/solr
[junit4] 2> 765114 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 765122 INFO (zkConnectionManagerCallback-6503-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 765122 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 765231 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 765238 INFO (zkConnectionManagerCallback-6505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 765238 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 765248 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 765254 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:53220_solr as DOWN
[junit4] 2> 765256 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 765256 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53220_solr
[junit4] 2> 765259 INFO (zkCallback-6436-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 765259 INFO (zkCallback-6458-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 765259 INFO (zkCallback-6471-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 765262 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 765262 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_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> 765263 INFO (zkCallback-6504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 765294 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [n:127.0.0.1:53220_solr ] o.a.s.h.a.Metric
[...truncated too long message...]
r Overseer (id=72081500221669382-127.0.0.1:53136_solr-n_0000000000) closing
[junit4] 2> 769480 INFO (OverseerStateUpdate-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [n:127.0.0.1:53136_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:53136_solr
[junit4] 2> 769480 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 769480 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 769480 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 769495 INFO (OverseerAutoScalingTriggerThread-72081500221669382-127.0.0.1:53136_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 769496 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 769496 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 769496 DEBUG (jetty-closer-6514-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 769584 INFO (closeThreadPool-6529-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72081500221669382-127.0.0.1:53136_solr-n_0000000000) closing
[junit4] 2> 769584 INFO (closeThreadPool-6529-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72081500221669382-127.0.0.1:53136_solr-n_0000000000) closing
[junit4] 2> 769641 INFO (jetty-closer-6514-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d2575a4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 769659 INFO (jetty-closer-6514-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3737c28b{/solr,null,UNAVAILABLE}
[junit4] 2> 769659 INFO (jetty-closer-6514-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 769681 INFO (zkCallback-6458-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 769809 INFO (jetty-closer-6514-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@15073342{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 769810 INFO (jetty-closer-6514-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@87abfff{/solr,null,UNAVAILABLE}
[junit4] 2> 769810 INFO (jetty-closer-6514-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 769821 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 770093 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72081500221669384-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> 2 /solr/autoscaling/events/sched_trigger_integration1
[junit4] 2>
[junit4] 2> 770097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:53118
[junit4] 2> 770097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53118
[junit4] 2> 770097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53118
[junit4] 2> 772202 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[439F8C5D7FA24CCE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=439F8C5D7FA24CCE -Dtests.slow=true -Dtests.locale=yi -Dtests.timezone=America/Havana -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 10.9s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([439F8C5D7FA24CCE:D084C42F215F17FA]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:126)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_439F8C5D7FA24CCE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=448, maxMBSortInHeap=6.359400334221196, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@149cf6f5), locale=yi, timezone=America/Havana
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=64590920,total=266338304
[junit4] 2> NOTE: All tests run in this JVM: [ZkShardTermsTest, TestSolrIndexConfig, ReturnFieldsTest, HdfsDirectoryFactoryTest, TestAnalyzedSuggestions, TestRequestStatusCollectionAPI, ReplaceNodeNoTargetTest, PeerSyncReplicationTest, PeerSyncWithLeaderTest, DistribJoinFromCollectionTest, RoutingToNodesWithPropertiesTest, TestSimExtremeIndexing, TestPolicyCloud, SimpleMLTQParserTest, OverseerSolrResponseUnsafeSerializationTest, DistributedIntervalFacetingTest, HdfsUnloadDistributedZkTest, Tagger2Test, HighlighterConfigTest, DocValuesMissingTest, MaxSizeAutoCommitTest, MultiSolrCloudTestCaseTest, TestPartialUpdateDeduplication, JWTAuthPluginTest, HdfsBasicDistributedZkTest, DistributedFacetSimpleRefinementLongTailTest, SearchHandlerTest, SynonymTokenizerTest, LeaderElectionContextKeyTest, TestDistribIDF, PreAnalyzedUpdateProcessorTest, TestTermsQParserPlugin, NodeLostTriggerIntegrationTest, TestMacroExpander, TestLegacyNumericRangeQueryBuilder, TestLegacyFieldCache, TestSolr4Spatial, TestSubQueryTransformerCrossCore, TestStressThreadBackup, BlockJoinFacetSimpleTest, SplitShardTest, TestTlogReplica, CustomCollectionTest, TestCaffeineCache, SpellCheckCollatorWithCollapseTest, TestStressRecovery, VMParamsZkACLAndCredentialsProvidersTest, DistributedDebugComponentTest, ExecutePlanActionTest, SolrIndexMetricsTest, TestManagedSynonymFilterFactory, DistributedQueryComponentOptimizationTest, TestCloudRecovery, TestBadConfig, VersionInfoTest, TestReRankQParserPlugin, TestCloudDeleteByQuery, IndexSizeEstimatorTest, TestCollationField, TestIntervalFaceting, TestExportTool, ConjunctionSolrSpellCheckerTest, TestJsonRangeFacets, TestDelegationWithHadoopAuth, TestApiFramework, TestJsonFacetErrors, LeaderTragicEventTest, TestShardHandlerFactory, TestConfigSetProperties, TestIBSimilarityFactory, TokenizerChainTest, OrderedExecutorTest, BadComponentTest, AnalysisErrorHandlingTest, TestQueryTypes, TestUnifiedSolrHighlighter, SolrLogAuditLoggerPluginTest, TestLeaderElectionWithEmptyReplica, TestSolrTestCaseJ4, TestJettySolrRunner, AnalysisAfterCoreReloadTest, TestRestoreCore, ScheduledTriggerIntegrationTest]
[junit4] Completed [310/905 (1!)] on J0 in 11.08s, 1 test, 1 failure <<< FAILURES!
[...truncated 46848 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1010/consoleText
[repro] Revision: a001f135f229a4c08afe4b6eb3dc11da5db91605
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3484 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=439F8C5D7FA24CCE -Dtests.slow=true -Dtests.locale=yi -Dtests.timezone=America/Havana -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...]