You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/23 23:22:50 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.2) - Build #
8871 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8871/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([F0BC5CBDA104F30:9C108DB984ED1404]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 14998 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1494425 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1494425 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1494426 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\data-dir-148-001
[junit4] 2> 1494426 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=38 numCloses=38
[junit4] 2> 1494426 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1494426 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F0BC5CBDA104F30]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1494429 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1494429 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001
[junit4] 2> 1494430 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1494430 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1494430 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1494516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer start zk server on port: 50201
[junit4] 2> 1494516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50201
[junit4] 2> 1494516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50201
[junit4] 2> 1494516 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50201
[junit4] 2> 1494518 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494521 INFO (zkConnectionManagerCallback-17088-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494521 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494524 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494526 INFO (zkConnectionManagerCallback-17090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494526 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494527 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494529 INFO (zkConnectionManagerCallback-17092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494529 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1494624 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@23783598{/solr,null,AVAILABLE}
[junit4] 2> 1494625 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32abead3{/solr,null,AVAILABLE}
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@10161e68{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?????}
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50218}
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e4afc03{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?????}
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50217}
[junit4] 2> 1494626 ERROR (jetty-launcher-17093-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1494626 ERROR (jetty-launcher-17093-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T22:51:38.112611200Z
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1494626 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T22:51:38.112611200Z
[junit4] 2> 1494628 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494628 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494632 INFO (zkConnectionManagerCallback-17097-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494632 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494632 INFO (zkConnectionManagerCallback-17095-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494632 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494632 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1494632 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1494726 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1494727 WARN (jetty-launcher-17093-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@742ff46d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494727 WARN (jetty-launcher-17093-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@742ff46d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494730 WARN (jetty-launcher-17093-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f4e4816[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494730 WARN (jetty-launcher-17093-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f4e4816[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494731 INFO (jetty-launcher-17093-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50201/solr
[junit4] 2> 1494731 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494731 INFO (zkConnectionManagerCallback-17108-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494731 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494811 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1494814 WARN (jetty-launcher-17093-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b5d89a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494814 WARN (jetty-launcher-17093-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b5d89a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494817 WARN (jetty-launcher-17093-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59418849[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494817 WARN (jetty-launcher-17093-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59418849[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494817 INFO (jetty-launcher-17093-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50201/solr
[junit4] 2> 1494820 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494821 INFO (zkConnectionManagerCallback-17121-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494821 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494858 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50218_solr
[junit4] 2> 1494859 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.Overseer Overseer (id=72093708087394309-127.0.0.1:50218_solr-n_0000000000) starting
[junit4] 2> 1494864 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50217_solr
[junit4] 2> 1494864 INFO (OverseerStateUpdate-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [n:127.0.0.1:50218_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50218_solr
[junit4] 2> 1494867 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1494868 INFO (zkCallback-17120-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1494868 INFO (OverseerStateUpdate-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1494869 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1494869 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1494869 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1494869 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1494869 WARN (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_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> 1494872 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50218_solr as DOWN
[junit4] 2> 1494874 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:50217_solr]
[junit4] 2> 1494876 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1494876 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50218_solr
[junit4] 2> 1494876 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1494877 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1494877 DEBUG (ScheduledTrigger-17126-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1494877 INFO (zkCallback-17120-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1494877 INFO (zkCallback-17107-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1494879 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1494879 WARN (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_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> 1494892 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1494899 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1494914 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50217.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494915 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50218.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494919 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50217.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494919 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50217.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494920 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50218.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494920 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50218.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1494920 INFO (jetty-launcher-17093-thread-2) [n:127.0.0.1:50217_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node2\.
[junit4] 2> 1494921 INFO (jetty-launcher-17093-thread-1) [n:127.0.0.1:50218_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node1\.
[junit4] 2> 1494938 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1494939 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1494941 INFO (zkConnectionManagerCallback-17144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1494941 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1494942 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1494943 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50201/solr ready
[junit4] 2> 1494957 INFO (qtp451835160-25818) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1494974 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1494975 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1494984 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1494984 DEBUG (qtp1957861235-25808) [n:127.0.0.1:50217_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1494985 INFO (qtp1957861235-25808) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 1494985 DEBUG (zkCallback-17107-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1494985 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1494987 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1494987 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1494991 INFO (qtp451835160-25818) [n:127.0.0.1:50218_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> 1494993 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1494993 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1494995 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1494996 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1494998 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1494998 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495000 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495001 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495002 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495003 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495004 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495006 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495007 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495008 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495009 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495010 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495011 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495012 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495014 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495015 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495016 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495017 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495019 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495020 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495021 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495023 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495024 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495025 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495027 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495028 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495029 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495030 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495032 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495033 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495034 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495035 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495037 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495039 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495041 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495042 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495043 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495044 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495045 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1495047 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1495048 INFO (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1495137 WARN (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_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> 1495137 DEBUG (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1495139 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1495143 INFO (qtp1957861235-25811) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1495145 DEBUG (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1495145 INFO (qtp451835160-25817) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1495147 INFO (qtp1957861235-25815) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1495151 DEBUG (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 551057498 sessionWrapper.createTime 551057491310299, this.sessionWrapper.createTime 551057491310299
[junit4] 2> 1495153 INFO (OverseerStateUpdate-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [n:127.0.0.1:50218_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:50217/solr",
[junit4] 2> "node_name":"127.0.0.1:50217_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1495153 INFO (OverseerStateUpdate-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [n:127.0.0.1:50218_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:50218/solr",
[junit4] 2> "node_name":"127.0.0.1:50218_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1495157 INFO (OverseerStateUpdate-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [n:127.0.0.1:50218_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:50218/solr",
[junit4] 2> "node_name":"127.0.0.1:50218_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1495331 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495331 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495336 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495341 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1495341 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1495345 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1495370 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1495370 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1495371 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1495477 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495477 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495477 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50217.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1495477 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1495478 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495478 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495478 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50218.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1495478 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1495480 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495480 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495480 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50218.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1495480 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1495574 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495574 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495576 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495576 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495576 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495576 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495578 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495578 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1495578 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495578 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1495578 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495578 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1495579 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495579 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495580 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495580 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495580 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495580 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495580 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495580 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495580 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495580 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495581 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495581 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1495581 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1495581 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495581 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495581 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495581 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1495581 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495582 INFO (qtp451835160-25819) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1495582 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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 1667523463419527168
[junit4] 2> 1495582 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1495582 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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 1667523463419527168
[junit4] 2> 1495582 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1495582 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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 1667523463419527168
[junit4] 2> 1495583 INFO (searcherExecutor-17146-thread-1-processing-n:127.0.0.1:50217_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1495583 INFO (searcherExecutor-17150-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1495583 INFO (searcherExecutor-17146-thread-1-processing-n:127.0.0.1:50217_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:50217_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> 1495583 INFO (searcherExecutor-17148-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1495583 INFO (searcherExecutor-17150-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:50218_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> 1495583 INFO (searcherExecutor-17148-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:50218_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> 1495584 INFO (searcherExecutor-17146-thread-1-processing-n:127.0.0.1:50217_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1495584 INFO (searcherExecutor-17150-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n4] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1495584 INFO (searcherExecutor-17148-thread-1-processing-n:127.0.0.1:50218_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1495586 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495586 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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> 1495586 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1495586 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495586 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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> 1495586 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1495587 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495587 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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> 1495590 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1495590 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1495590 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:50218/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 1495590 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:50218/solr START replicas=[https://127.0.0.1:50217/solr/testScheduledTrigger_shard1_replica_n1/, https://127.0.0.1:50218/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1495590 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:50218/solr DONE. We have no versions. sync failed.
[junit4] 2> 1495596 INFO (qtp451835160-25817) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1495596 INFO (qtp1957861235-25815) [n:127.0.0.1:50217_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> 1495597 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1495597 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1495597 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72093708087394309-core_node5-n_0000000000
[junit4] 2> 1495598 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:50218/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 1495685 INFO (zkCallback-17107-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> 1495685 INFO (zkCallback-17107-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> 1495686 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1495687 INFO (qtp451835160-25812) [n:127.0.0.1:50218_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=417
[junit4] 2> 1495739 DEBUG (ScheduledTrigger-17126-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1495775 INFO (zkCallback-17107-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1495775 INFO (zkCallback-17107-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> 1495775 INFO (zkCallback-17107-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> 1496453 INFO (qtp451835160-25819) [n:127.0.0.1:50218_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=1299
[junit4] 2> 1496453 INFO (qtp1957861235-25814) [n:127.0.0.1:50217_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=1306
[junit4] 2> 1496453 DEBUG (OverseerThreadFactory-17129-thread-1-processing-n:127.0.0.1:50218_solr) [n:127.0.0.1:50218_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1496455 INFO (qtp451835160-25818) [n:127.0.0.1:50218_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> 1496538 INFO (zkCallback-17107-thread-4) [ ] 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> 1496538 INFO (zkCallback-17107-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> 1496538 INFO (zkCallback-17107-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> 1496538 INFO (zkCallback-17120-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> 1496538 INFO (zkCallback-17120-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> 1496538 INFO (qtp451835160-25818) [n:127.0.0.1:50218_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=1800
[junit4] 2> 1496539 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1496541 INFO (qtp451835160-25817) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n2:INDEX.sizeInBytes&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1496543 INFO (qtp451835160-25812) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1496543 INFO (qtp1957861235-25811) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1496544 INFO (qtp1957861235-25808) [n:127.0.0.1:50217_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1496544 DEBUG (qtp451835160-25882) [n:127.0.0.1:50218_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1496545 INFO (qtp451835160-25882) [n:127.0.0.1:50218_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1496545 DEBUG (zkCallback-17107-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1496545 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1496545 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1496546 DEBUG (OverseerAutoScalingTriggerThread-72093708087394309-127.0.0.1:50218_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1496547 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31379cb4{/solr,null,AVAILABLE}
[junit4] 2> 1496548 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24614562{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?????}
[junit4] 2> 1496548 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 1496548 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50279}
[junit4] 2> 1496548 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1496548 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1496549 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1496549 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1496549 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1496549 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T22:51:40.035608Z
[junit4] 2> 1496550 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1496557 INFO (zkConnectionManagerCallback-17164-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1496557 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1496558 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1496600 DEBUG (ScheduledTrigger-17126-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1496656 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1496657 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@625a5d32[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496657 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@625a5d32[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496660 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@e4d9f10[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496660 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@e4d9f10[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496660 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50201/solr
[junit4] 2> 1496661 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1496663 INFO (zkConnectionManagerCallback-17175-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1496663 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1496666 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1496669 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:50279_solr as DOWN
[junit4] 2> 1496669 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1496669 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50279_solr
[junit4] 2> 1496670 INFO (zkCallback-17107-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1496670 INFO (zkCallback-17120-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1496670 INFO (zkCallback-17143-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1496670 INFO (zkCallback-17174-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1496671 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1496671 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_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> 1496688 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1496705 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50279.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1496711 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50279.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@af9e1e3
[junit4] 2> 1496711 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [n:127.0.0.1:50279_solr ] o.a.s.m.r.SolrJmx
[...truncated too long message...]
> 1544843 DEBUG (closeThreadPool-17201-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 1544843 DEBUG (closeThreadPool-17201-thread-1) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1544843 DEBUG (closeThreadPool-17201-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1544843 INFO (closeThreadPool-17201-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72093708087394309-127.0.0.1:50218_solr-n_0000000000) closing
[junit4] 2> 1544941 INFO (jetty-closer-17184-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@24614562{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?}
[junit4] 2> 1544941 INFO (jetty-closer-17184-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31379cb4{/solr,null,UNAVAILABLE}
[junit4] 2> 1544941 INFO (jetty-closer-17184-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1544953 INFO (zkCallback-17107-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1544955 INFO (jetty-closer-17184-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7e4afc03{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?}
[junit4] 2> 1544955 INFO (jetty-closer-17184-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@23783598{/solr,null,UNAVAILABLE}
[junit4] 2> 1544955 INFO (jetty-closer-17184-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1545083 INFO (jetty-closer-17184-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72093708087394309-127.0.0.1:50218_solr-n_0000000000) closing
[junit4] 2> 1545084 INFO (jetty-closer-17184-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10161e68{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:?}
[junit4] 2> 1545084 INFO (jetty-closer-17184-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32abead3{/solr,null,UNAVAILABLE}
[junit4] 2> 1545084 INFO (jetty-closer-17184-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1545086 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1545344 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 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> 25 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72093708087394309-core_node5-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/overseer/queue
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1545349 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:50201
[junit4] 2> 1545349 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50201
[junit4] 2> 1545349 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50201
[junit4] 2> 1548113 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F0BC5CBDA104F30]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=F0BC5CBDA104F30 -Dtests.slow=true -Dtests.locale=lrc -Dtests.timezone=America/Goose_Bay -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 53.7s J1 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([F0BC5CBDA104F30:9C108DB984ED1404]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F0BC5CBDA104F30-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1349, maxMBSortInHeap=5.3524273376600995, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@e9a63e8), locale=lrc, timezone=America/Goose_Bay
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=207356816,total=482344960
[junit4] 2> NOTE: All tests run in this JVM: [BooleanFieldTest, TestPseudoReturnFields, TestSolrIndexConfig, TestRetrieveFieldsOptimizer, DistributedQueryComponentCustomSortTest, MetricsHandlerTest, MultiThreadedOCPTest, TestSmileRequest, TestRestoreCore, IndexSizeEstimatorTest, TestPivotHelperCode, CollectionTooManyReplicasTest, TermVectorComponentTest, TestManagedSchema, CachingDirectoryFactoryTest, FastVectorHighlighterTest, TestWithCollection, TestBadConfig, TestAnalyzedSuggestions, TestPullReplica, DirectSolrConnectionTest, TestSimExtremeIndexing, TestShortCircuitedRequests, IndexSizeTriggerTest, TestPostingsSolrHighlighter, LeaderElectionIntegrationTest, TestExactSharedStatsCache, OverseerCollectionConfigSetProcessorTest, TestNumericTokenStream, SolrMetricManagerTest, UniqFieldsUpdateProcessorFactoryTest, TestLuceneIndexBackCompat, SolrJmxReporterTest, TestHdfsBackupRestoreCore, TestMultiWordSynonyms, HLLSerializationTest, ChaosMonkeySafeLeaderTest, DistributedFacetPivotLongTailTest, RandomizedTaggerTest, LeaderVoteWaitTimeoutTest, DistributedQueueTest, NumericFieldsTest, CreateRoutedAliasTest, TestMinMaxOnMultiValuedField, CollectionsAPISolrJTest, TestRandomDVFaceting, TestSimClusterStateProvider, DeleteShardTest, ZkShardTermsTest, ZkDynamicConfigTest, TestShardHandlerFactory, QueryElevationComponentTest, TestRandomRequestDistribution, ExecutePlanActionTest, TestPerFieldSimilarityWithDefaultOverride, TestSurroundQueryParser, TestFaceting, TestRequestStatusCollectionAPI, ExternalFileFieldSortTest, XsltUpdateRequestHandlerTest, DateRangeFieldTest, BigEndianAscendingWordDeserializerTest, TestInPlaceUpdateWithRouteField, ZkStateReaderTest, SolrIndexSearcherTest, DOMUtilTest, ZookeeperStatusHandlerTest, CustomCollectionTest, SolrLogAuditLoggerPluginTest, ClusterStateMockUtilTest, TestCloudManagedSchema, TestPolicyCloud, TestRestManager, EchoParamsTest, TestMergePolicyConfig, TestCloudDeleteByQuery, TestCloudJSONFacetSKG, HdfsBasicDistributedZkTest, XCJFQueryTest, TestJavabinTupleStreamParser, TestFieldCacheReopen, TestSchemaField, TestExclusionRuleCollectionAccess, TestConfigSetsAPIExclusivity, TestSimPolicyCloud, TestDistributedSearch, QueryEqualityTest, TestLMDirichletSimilarityFactory, PreAnalyzedUpdateProcessorTest, TestObjectReleaseTracker, JWTAuthPluginTest, TestInitQParser, TestAuthenticationFramework, TestCloudPseudoReturnFields, TestCloudRecovery2, TestDistribDocBasedVersion, TestLRUStatsCacheCloud, TestLeaderElectionZkExpiry, TestStressLiveNodes, CollectionsAPIDistributedZkTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [596/913 (1!)] on J1 in 53.71s, 1 test, 1 failure <<< FAILURES!
[...truncated 53387 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8871/consoleText
[repro] Revision: 675956c0041b18d48a7c059ea458c49f5310d74a
[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 3158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=F0BC5CBDA104F30 -Dtests.slow=true -Dtests.locale=lrc -Dtests.timezone=America/Goose_Bay -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...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...]