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/25 21:25:40 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.2) - Build #
8880 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8880/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([680BBA020876B2C0:FB10F270568BE9F4]: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 15017 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1545598 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[680BBA020876B2C0]-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> 1545601 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[680BBA020876B2C0]-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_680BBA020876B2C0-001\data-dir-135-001
[junit4] 2> 1545601 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[680BBA020876B2C0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1545603 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[680BBA020876B2C0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1545603 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[680BBA020876B2C0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1545607 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1545609 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] 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_680BBA020876B2C0-001\tempDir-001
[junit4] 2> 1545609 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1545610 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1545610 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1545744 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer start zk server on port: 55551
[junit4] 2> 1545744 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55551
[junit4] 2> 1545744 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55551
[junit4] 2> 1545744 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55551
[junit4] 2> 1545749 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545756 INFO (zkConnectionManagerCallback-13098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545756 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545760 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545771 INFO (zkConnectionManagerCallback-13100-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545771 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545772 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545780 INFO (zkConnectionManagerCallback-13102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545788 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545931 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1545931 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1545931 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1545931 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1545931 INFO (jetty-launcher-13103-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> 1545931 INFO (jetty-launcher-13103-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> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c08cc10{/solr,null,AVAILABLE}
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7594128e{/solr,null,AVAILABLE}
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@20cd1893{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55564}
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.e.j.s.Server Started @1488071ms
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55564}
[junit4] 2> 1545932 ERROR (jetty-launcher-13103-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1545932 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-25T20:46:42.496797600Z
[junit4] 2> 1545938 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@30036e88{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55571}
[junit4] 2> 1545938 INFO (jetty-launcher-13103-thread-2) [ ] o.e.j.s.Server Started @1488072ms
[junit4] 2> 1545938 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55571}
[junit4] 2> 1545939 ERROR (jetty-launcher-13103-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-25T20:46:42.503082200Z
[junit4] 2> 1545939 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545940 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1545944 INFO (zkConnectionManagerCallback-13105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545944 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545949 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1545949 INFO (zkConnectionManagerCallback-13107-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1545949 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1545953 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1546102 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1546104 WARN (jetty-launcher-13103-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1e6b90a5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546104 WARN (jetty-launcher-13103-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1e6b90a5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546109 WARN (jetty-launcher-13103-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@537f1954[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546109 WARN (jetty-launcher-13103-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@537f1954[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546111 INFO (jetty-launcher-13103-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55551/solr
[junit4] 2> 1546113 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1546118 INFO (zkConnectionManagerCallback-13118-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1546118 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1546235 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1546237 WARN (jetty-launcher-13103-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c3c8c7b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546237 WARN (jetty-launcher-13103-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c3c8c7b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546242 WARN (jetty-launcher-13103-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4b338850[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546242 WARN (jetty-launcher-13103-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4b338850[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1546243 INFO (jetty-launcher-13103-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55551/solr
[junit4] 2> 1546245 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1546249 INFO (zkConnectionManagerCallback-13131-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1546249 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1546267 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55571_solr
[junit4] 2> 1546269 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.Overseer Overseer (id=72104171169316870-127.0.0.1:55571_solr-n_0000000000) starting
[junit4] 2> 1546271 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55564_solr
[junit4] 2> 1546273 INFO (zkCallback-13117-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1546273 INFO (zkCallback-13130-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1546275 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1546275 WARN (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_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> 1546275 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55571_solr as DOWN
[junit4] 2> 1546275 INFO (OverseerStateUpdate-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [n:127.0.0.1:55571_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55571_solr
[junit4] 2> 1546275 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1546275 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1546279 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55571_solr
[junit4] 2> 1546279 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1546279 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1546279 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1546279 INFO (zkCallback-13117-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1546279 INFO (zkCallback-13130-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1546279 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1546279 WARN (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_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> 1546284 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:55571_solr, 127.0.0.1:55564_solr]
[junit4] 2> 1546284 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1546284 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1546284 DEBUG (ScheduledTrigger-13137-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1546307 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1546317 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1546364 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55564.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546369 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55571.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546378 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55564.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546378 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55564.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546380 INFO (jetty-launcher-13103-thread-1) [n:127.0.0.1:55564_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_680BBA020876B2C0-001\tempDir-001\node1\.
[junit4] 2> 1546381 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55571.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546382 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55571.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1546385 INFO (jetty-launcher-13103-thread-2) [n:127.0.0.1:55571_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_680BBA020876B2C0-001\tempDir-001\node2\.
[junit4] 2> 1546427 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1546429 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1546433 INFO (zkConnectionManagerCallback-13154-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1546433 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1546436 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1546438 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55551/solr ready
[junit4] 2> 1546450 INFO (qtp1053071136-19876) [n:127.0.0.1:55571_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1546456 INFO (qtp772465442-19870) [n:127.0.0.1:55564_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1546460 INFO (qtp772465442-19870) [n:127.0.0.1:55564_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1546466 INFO (qtp1053071136-19873) [n:127.0.0.1:55571_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> 1546466 DEBUG (qtp772465442-19868) [n:127.0.0.1:55564_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1546467 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1546468 DEBUG (zkCallback-13130-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1546468 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1546469 INFO (qtp772465442-19869) [n:127.0.0.1:55564_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> 1546471 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1546472 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1546474 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1546475 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1546478 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1546481 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546484 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546487 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546489 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546492 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546494 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546496 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546499 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546502 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546504 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546506 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546509 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546512 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546514 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546517 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546519 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546522 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546524 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546527 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546530 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546533 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546536 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546539 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546542 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546544 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546547 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546550 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546552 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546555 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546558 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546561 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546563 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546567 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546570 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546573 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546575 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546578 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546581 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546584 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546587 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1546591 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1546593 INFO (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1546737 WARN (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_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> 1546739 DEBUG (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1546744 INFO (qtp772465442-19870) [n:127.0.0.1:55564_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 1546749 INFO (qtp1053071136-19875) [n:127.0.0.1:55571_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> 1546750 DEBUG (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1546754 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1546759 INFO (qtp1053071136-19876) [n:127.0.0.1:55571_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> 1546762 DEBUG (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 710711478 sessionWrapper.createTime 710711468942000, this.sessionWrapper.createTime 710711468942000
[junit4] 2> 1546768 INFO (OverseerStateUpdate-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [n:127.0.0.1:55571_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55571/solr",
[junit4] 2> "node_name":"127.0.0.1:55571_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1546772 INFO (OverseerStateUpdate-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [n:127.0.0.1:55571_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55564/solr",
[junit4] 2> "node_name":"127.0.0.1:55564_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1546778 INFO (OverseerStateUpdate-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [n:127.0.0.1:55571_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55564/solr",
[junit4] 2> "node_name":"127.0.0.1:55564_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1547048 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547049 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547050 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547080 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547080 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547087 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547147 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547147 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547148 INFO (qtp772465442-19867) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1547396 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547396 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547396 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55571.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1547396 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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_680BBA020876B2C0-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_680BBA020876B2C0-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1547401 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547401 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547401 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55564.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1547401 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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_680BBA020876B2C0-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_680BBA020876B2C0-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1547409 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547409 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547409 INFO (qtp772465442-19867) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55564.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1547409 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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_680BBA020876B2C0-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_680BBA020876B2C0-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1547553 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547553 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547558 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547558 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547561 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547561 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547561 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547562 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547562 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547562 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547564 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547564 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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 1667696798338121728
[junit4] 2> 1547566 INFO (searcherExecutor-13156-thread-1-processing-n:127.0.0.1:55571_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547566 INFO (searcherExecutor-13156-thread-1-processing-n:127.0.0.1:55571_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55571_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> 1547566 INFO (searcherExecutor-13156-thread-1-processing-n:127.0.0.1:55571_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:55571_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> 1547572 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547572 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547572 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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_node3=0}, version=0}
[junit4] 2> 1547572 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1547574 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547574 INFO (qtp772465442-19867) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547574 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547574 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547578 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547579 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoute in=9999ms
[junit4] 2> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547579 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547579 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1547579 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547583 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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 1667696798358044672
[junit4] 2> 1547585 INFO (searcherExecutor-13160-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547585 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547585 INFO (searcherExecutor-13160-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55564_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> 1547585 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547585 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547586 INFO (searcherExecutor-13160-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:55564_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> 1547587 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547588 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1547588 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547589 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547589 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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 1667696798364336128
[junit4] 2> 1547591 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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_node3=0}, version=1}
[junit4] 2> 1547591 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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> 1547591 INFO (searcherExecutor-13158-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1547591 INFO (searcherExecutor-13158-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55564_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> 1547593 INFO (searcherExecutor-13158-thread-1-processing-n:127.0.0.1:55564_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:55564_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> 1547594 INFO (qtp772465442-19868) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1547594 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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> 1547626 DEBUG (ScheduledTrigger-13137-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> 1548250 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1548250 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1548250 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55571/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1548250 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:55571/solr START replicas=[http://127.0.0.1:55564/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:55564/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1548252 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:55571/solr DONE. We have no versions. sync failed.
[junit4] 2> 1548254 INFO (qtp772465442-19870) [n:127.0.0.1:55564_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1548254 INFO (qtp772465442-19939) [n:127.0.0.1:55564_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> 1548254 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1548254 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 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> 1548254 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72104171169316870-core_node3-n_0000000000
[junit4] 2> 1548257 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55571/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1548392 INFO (zkCallback-13130-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> 1548393 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1548401 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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=1012
[junit4] 2> 1548531 INFO (zkCallback-13130-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> 1548531 INFO (zkCallback-13130-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> 1548942 INFO (qtp772465442-19868) [n:127.0.0.1:55564_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=1416
[junit4] 2> 1548942 INFO (qtp772465442-19867) [n:127.0.0.1:55564_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=1416
[junit4] 2> 1548942 DEBUG (OverseerThreadFactory-13139-thread-1-processing-n:127.0.0.1:55571_solr) [n:127.0.0.1:55571_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1548946 INFO (qtp772465442-19869) [n:127.0.0.1:55564_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> 1548965 DEBUG (ScheduledTrigger-13137-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> 1549077 INFO (zkCallback-13117-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> 1549077 INFO (zkCallback-13117-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> 1549077 INFO (zkCallback-13130-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> 1549077 INFO (zkCallback-13130-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> 1549079 INFO (zkCallback-13117-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> 1549079 INFO (qtp772465442-19869) [n:127.0.0.1:55564_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=1952
[junit4] 2> 1549079 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1549086 INFO (qtp772465442-19939) [n:127.0.0.1:55564_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> 1549089 INFO (qtp772465442-19870) [n:127.0.0.1:55564_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=2
[junit4] 2> 1549090 INFO (qtp1053071136-19876) [n:127.0.0.1:55571_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> 1549094 INFO (qtp1053071136-19877) [n:127.0.0.1:55571_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> 1549094 DEBUG (qtp1053071136-19875) [n:127.0.0.1:55571_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1549094 INFO (qtp1053071136-19875) [n:127.0.0.1:55571_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] 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> 1549097 DEBUG (zkCallback-13130-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1549097 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1549097 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1549099 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e1f7696{/solr,null,AVAILABLE}
[junit4] 2> 1549099 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1549099 DEBUG (OverseerAutoScalingTriggerThread-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1549100 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a0668d0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55786}
[junit4] 2> 1549100 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.s.Server Started @1490439ms
[junit4] 2> 1549100 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55786}
[junit4] 2> 1549101 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1549101 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1549102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1549102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1549102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1549102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-25T20:46:45.666259200Z
[junit4] 2> 1549102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549106 INFO (zkConnectionManagerCallback-13174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549106 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549108 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1549149 INFO (OverseerCollectionConfigSetProcessor-72104171169316870-127.0.0.1:55571_solr-n_0000000000) [n:127.0.0.1:55571_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1549253 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1549255 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@25051d05[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1549255 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@25051d05[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1549259 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@26c23b20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1549259 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@26c23b20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1549260 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55551/solr
[junit4] 2> 1549261 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1549264 INFO (zkConnectionManagerCallback-13185-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549264 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1549271 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1549276 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55786_solr as DOWN
[junit4] 2> 1549277 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1549277 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55786_solr
[junit4] 2> 1549279 INFO (zkCallback-13117-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1549279 INFO (zkCallback-13153-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1549279 INFO (zkCallback-13130-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1549279 INFO (zkCallback-13184-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1549280 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1549281 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_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> 1549312 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1549340 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55786.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1549351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55786.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1549351 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55786.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fdf1c5
[junit4] 2> 1549354 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [n:127.0.0.1:55786_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_680BBA020876B2C0-001\tempDir-001\node3\.
[junit4] 2> 1549373 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ]
[...truncated too long message...]
osing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38dbb8f2: rootName = solr_55564, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@122437d0
[junit4] 2> 1595874 INFO (jetty-closer-13194-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1595924 INFO (jetty-closer-13194-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72104171169316870-127.0.0.1:55571_solr-n_0000000000) closing
[junit4] 2> 1595924 INFO (zkCallback-13117-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1595924 INFO (zkCallback-13184-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1595924 INFO (jetty-closer-13194-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@30036e88{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1595926 INFO (jetty-closer-13194-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7594128e{/solr,null,UNAVAILABLE}
[junit4] 2> 1595926 INFO (jetty-closer-13194-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1596010 INFO (jetty-closer-13194-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@20cd1893{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1596011 INFO (jetty-closer-13194-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c08cc10{/solr,null,UNAVAILABLE}
[junit4] 2> 1596012 INFO (jetty-closer-13194-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1596088 INFO (jetty-closer-13194-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6a0668d0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1596090 INFO (jetty-closer-13194-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e1f7696{/solr,null,UNAVAILABLE}
[junit4] 2> 1596090 INFO (jetty-closer-13194-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1596094 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1596266 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1596271 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:55551
[junit4] 2> 1596271 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55551
[junit4] 2> 1596271 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55551
[junit4] 2> 1597855 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[680BBA020876B2C0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=680BBA020876B2C0 -Dtests.slow=true -Dtests.locale=fr-TG -Dtests.timezone=America/Catamarca -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 52.3s J1 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([680BBA020876B2C0:FB10F270568BE9F4]: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_680BBA020876B2C0-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1783, maxMBSortInHeap=6.555612952880492, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@670c0128), locale=fr-TG, timezone=America/Catamarca
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=219830776,total=481296384
[junit4] 2> NOTE: All tests run in this JVM: [TestPackages, BasicAuthIntegrationTest, TestCollectionAPI, TestWriterPerf, TestRandomDVFaceting, TestJsonFacetsStatsParsing, MetricsHandlerTest, ConjunctionSolrSpellCheckerTest, QueryEqualityTest, SpellCheckCollatorWithCollapseTest, DistributedQueryComponentCustomSortTest, TestInPlaceUpdateWithRouteField, CustomCollectionTest, TestBM25SimilarityFactory, TestDistributedTracing, TestManagedStopFilterFactory, TestDistributedMap, TestFieldCacheReopen, TestManagedSchema, ExternalFileFieldSortTest, TestLegacyNumericUtils, TestCloudRecovery, OverseerStatusTest, TestNestedUpdateProcessor, SearchHandlerTest, TestJavabinTupleStreamParser, TestSurroundQueryParser, TestSmileRequest, TestPullReplica, TestSchemaField, NumberUtilsTest, ExecutePlanActionTest, CachingDirectoryFactoryTest, TestSimPolicyCloud, TestImplicitCoreProperties, VersionInfoTest, TestTlogReplica, TestLegacyFieldCache, PrimitiveFieldTypeTest, HdfsUnloadDistributedZkTest, TestPolicyCloud, DeleteShardTest, MaxSizeAutoCommitTest, TestNumericTokenStream, BasicDistributedZk2Test, TestLuceneIndexBackCompat, LeaderElectionIntegrationTest, DirectSolrConnectionTest, TestRequestStatusCollectionAPI, WrapperMergePolicyFactoryTest, TestAnalyzedSuggestions, TestCollapseQParserPlugin, DistributedTermsComponentTest, SSLMigrationTest, TestRangeQuery, TestSolrCloudWithSecureImpersonation, BooleanFieldTest, TestSimExtremeIndexing, ShowFileRequestHandlerTest, DistributedQueueTest, ZookeeperStatusHandlerTest, TestLocalStatsCacheCloud, CreateRoutedAliasTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestLMDirichletSimilarityFactory, TestApiFramework, JWTAuthPluginTest, TestReload, CurrencyRangeFacetCloudTest, SolrIndexSearcherTest, RecoveryAfterSoftCommitTest, GraphQueryTest, TestPostingsSolrHighlighter, DateRangeFieldTest, TestXmlQParserPlugin, TestFiltering, TestMergePolicyConfig, TestSchemaResource, CollectionTooManyReplicasTest, TestHdfsBackupRestoreCore, TestDistributedSearch, XsltUpdateRequestHandlerTest, DefaultValueUpdateProcessorTest, DebugComponentTest, FieldAnalysisRequestHandlerTest, HdfsDirectoryTest, PeerSyncWithLeaderTest, TestMinMaxOnMultiValuedField, CategoryRoutedAliasUpdateProcessorTest, DeleteNodeTest, FullSolrCloudDistribCmdsTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest, MultiSolrCloudTestCaseTest, PackageManagerCLITest, SolrCloudExampleTest, SystemCollectionCompatTest, TestCloudConsistency, TestHashPartitioner, TestOnReconnectListenerSupport, TestRequestForwarding, TestSSLRandomization, TestShortCircuitedRequests, VMParamsZkACLAndCredentialsProvidersTest, ZkFailoverTest, ZkSolrClientTest, MetricTriggerTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [616/913 (1!)] on J1 in 52.30s, 1 test, 1 failure <<< FAILURES!
[...truncated 53347 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8880/consoleText
[repro] Revision: 675956c0041b18d48a7c059ea458c49f5310d74a
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=680BBA020876B2C0 -Dtests.slow=true -Dtests.locale=fr-TG -Dtests.timezone=America/Catamarca -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]