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/22 10:29:16 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.2) - Build #
8865 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8865/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([7C1E5F7ED8AF67CD:EF05170C86523CF9]: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 14953 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1615675 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[7C1E5F7ED8AF67CD]-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> 1615675 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[7C1E5F7ED8AF67CD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1615676 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[7C1E5F7ED8AF67CD]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\data-dir-153-001
[junit4] 2> 1615677 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[7C1E5F7ED8AF67CD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1615678 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[7C1E5F7ED8AF67CD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1615680 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1615682 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001
[junit4] 2> 1615682 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1615683 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1615683 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1615774 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer start zk server on port: 60481
[junit4] 2> 1615774 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:60481
[junit4] 2> 1615774 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60481
[junit4] 2> 1615774 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60481
[junit4] 2> 1615776 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615780 INFO (zkConnectionManagerCallback-14036-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615780 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1615783 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615786 INFO (zkConnectionManagerCallback-14038-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615786 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1615787 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615788 INFO (zkConnectionManagerCallback-14040-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615788 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1615883 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1615883 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1615883 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1615883 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1615883 INFO (jetty-launcher-14041-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> 1615883 INFO (jetty-launcher-14041-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> 1615884 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1615884 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1615884 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1615884 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19df298f{/solr,null,AVAILABLE}
[junit4] 2> 1615885 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1615885 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1615885 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5b4323ff{/solr,null,AVAILABLE}
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2d4d2181{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60494}
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.e.j.s.Server Started @1554112ms
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60494}
[junit4] 2> 1615886 ERROR (jetty-launcher-14041-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1615886 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T09:48:28.373638500Z
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ad3c68d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60501}
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.e.j.s.Server Started @1554114ms
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60501}
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615887 ERROR (jetty-launcher-14041-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T09:48:28.374129200Z
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615887 INFO (zkConnectionManagerCallback-14043-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1615887 INFO (zkConnectionManagerCallback-14045-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615887 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1615890 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1615991 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1615992 WARN (jetty-launcher-14041-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@72316aad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1615992 WARN (jetty-launcher-14041-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@72316aad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1615995 WARN (jetty-launcher-14041-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43ae2c39[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1615995 WARN (jetty-launcher-14041-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43ae2c39[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1615996 INFO (jetty-launcher-14041-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60481/solr
[junit4] 2> 1615998 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1615999 INFO (zkConnectionManagerCallback-14056-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1615999 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1616073 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60501_solr
[junit4] 2> 1616073 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.Overseer Overseer (id=72085263852109829-127.0.0.1:60501_solr-n_0000000000) starting
[junit4] 2> 1616078 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60501_solr
[junit4] 2> 1616078 INFO (OverseerStateUpdate-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:60501_solr
[junit4] 2> 1616078 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1616079 INFO (OverseerStateUpdate-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1616079 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1616079 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1616079 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1616079 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1616080 WARN (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_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> 1616082 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:60501_solr]
[junit4] 2> 1616082 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1616082 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1616083 DEBUG (ScheduledTrigger-14061-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1616083 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1616083 WARN (jetty-launcher-14041-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4df0747d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1616083 WARN (jetty-launcher-14041-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4df0747d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1616086 WARN (jetty-launcher-14041-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@638a6eab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1616086 WARN (jetty-launcher-14041-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@638a6eab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1616087 INFO (jetty-launcher-14041-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60481/solr
[junit4] 2> 1616088 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1616089 INFO (zkConnectionManagerCallback-14074-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1616089 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1616093 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1616095 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60494_solr as DOWN
[junit4] 2> 1616095 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1616095 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60494_solr
[junit4] 2> 1616097 INFO (zkCallback-14055-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1616097 INFO (zkCallback-14073-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1616098 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1616098 WARN (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_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> 1616098 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1616120 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1616123 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60501.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616130 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60501.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616131 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60501.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616131 INFO (jetty-launcher-14041-thread-2) [n:127.0.0.1:60501_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node2\.
[junit4] 2> 1616140 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60494.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616146 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60494.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616146 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60494.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616148 INFO (jetty-launcher-14041-thread-1) [n:127.0.0.1:60494_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node1\.
[junit4] 2> 1616159 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1616160 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1616163 INFO (zkConnectionManagerCallback-14092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1616163 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1616163 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1616165 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60481/solr ready
[junit4] 2> 1616172 INFO (qtp1142173823-22929) [n:127.0.0.1:60501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1616177 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616178 INFO (qtp1142173823-22930) [n:127.0.0.1:60501_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1616181 INFO (qtp1142173823-22930) [n:127.0.0.1:60501_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> 1616181 DEBUG (qtp596302720-22923) [n:127.0.0.1:60494_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1616182 INFO (qtp596302720-22923) [n:127.0.0.1:60494_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 1616182 DEBUG (zkCallback-14055-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1616183 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1616183 INFO (qtp596302720-22925) [n:127.0.0.1:60494_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> 1616183 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1616183 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1616183 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1616187 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1616189 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1616190 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616192 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616193 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616194 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616195 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616197 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616198 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616200 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616201 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616203 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616204 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616205 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616207 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616208 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616210 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616210 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616212 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616213 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616215 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616216 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616217 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616218 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616220 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616221 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616223 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616224 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616225 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616226 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616228 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616229 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616231 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616232 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616233 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616235 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616241 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616242 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616244 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616245 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616247 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616248 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1616250 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1616252 INFO (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1616348 WARN (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_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> 1616349 DEBUG (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1616352 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616356 INFO (qtp1142173823-22927) [n:127.0.0.1:60501_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> 1616356 DEBUG (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1616359 INFO (qtp596302720-22923) [n:127.0.0.1:60494_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> 1616362 INFO (qtp1142173823-22929) [n:127.0.0.1:60501_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> 1616364 DEBUG (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 422208596 sessionWrapper.createTime 422208587610900, this.sessionWrapper.createTime 422208587610900
[junit4] 2> 1616367 INFO (OverseerStateUpdate-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_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:60501/solr",
[junit4] 2> "node_name":"127.0.0.1:60501_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1616369 INFO (OverseerStateUpdate-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_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:60494/solr",
[junit4] 2> "node_name":"127.0.0.1:60494_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1616371 INFO (OverseerStateUpdate-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_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:60494/solr",
[junit4] 2> "node_name":"127.0.0.1:60494_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1616557 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616557 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616561 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616589 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616590 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616594 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616610 INFO (qtp596302720-22921) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1616610 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1616615 INFO (qtp596302720-22924) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1616829 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616829 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616830 INFO (qtp596302720-22921) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60494.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616830 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1616837 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616837 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616837 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616837 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616837 INFO (qtp596302720-22924) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60494.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616837 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60501.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1616837 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1616837 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1616950 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616950 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616950 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616950 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616953 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616953 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616953 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616953 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1616953 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616953 INFO (qtp596302720-22921) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1616955 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616955 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616956 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616956 INFO (qtp596302720-22924) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1616956 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616956 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616956 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616956 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616957 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616957 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616957 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1616957 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616957 INFO (qtp596302720-22921) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1616957 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616957 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616957 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616957 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616957 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616958 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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 1667383594262200320
[junit4] 2> 1616958 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616958 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616958 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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 1667383594262200320
[junit4] 2> 1616958 INFO (qtp596302720-22924) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1616959 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616960 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616960 INFO (searcherExecutor-14098-thread-1-processing-n:127.0.0.1:60501_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:60501_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1616960 INFO (searcherExecutor-14098-thread-1-processing-n:127.0.0.1:60501_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:60501_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> 1616960 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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 1667383594264297472
[junit4] 2> 1616960 INFO (searcherExecutor-14094-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1616960 INFO (searcherExecutor-14094-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:60494_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> 1616961 INFO (searcherExecutor-14094-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:60494_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> 1616961 INFO (searcherExecutor-14096-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1616961 INFO (searcherExecutor-14096-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:60494_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> 1616961 INFO (searcherExecutor-14098-thread-1-processing-n:127.0.0.1:60501_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:60501_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> 1616962 INFO (searcherExecutor-14096-thread-1-processing-n:127.0.0.1:60494_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:60494_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> 1616963 INFO (qtp596302720-22921) [n:127.0.0.1:60494_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1616963 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616964 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616964 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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_node3=0, core_node5=0}, version=1}
[junit4] 2> 1616964 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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> 1616965 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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, core_node5=0}, version=2}
[junit4] 2> 1616965 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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> 1616968 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616968 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616968 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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:60501/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1616969 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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:60501/solr START replicas=[http://127.0.0.1:60494/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:60494/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1616969 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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:60501/solr DONE. We have no versions. sync failed.
[junit4] 2> 1616971 INFO (qtp596302720-22923) [n:127.0.0.1:60494_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> 1616971 INFO (qtp596302720-22993) [n:127.0.0.1:60494_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> 1616971 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616971 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1616971 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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/72085263852109829-core_node3-n_0000000000
[junit4] 2> 1616973 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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:60501/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1616998 DEBUG (ScheduledTrigger-14061-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1617068 INFO (zkCallback-14055-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> 1617068 INFO (zkCallback-14055-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> 1617068 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1617071 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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=559
[junit4] 2> 1617162 INFO (zkCallback-14055-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> 1617162 INFO (zkCallback-14055-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> 1617162 INFO (zkCallback-14055-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> 1617890 INFO (qtp596302720-22921) [n:127.0.0.1:60494_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=1456
[junit4] 2> 1617890 INFO (qtp596302720-22924) [n:127.0.0.1:60494_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=1455
[junit4] 2> 1617890 DEBUG (OverseerThreadFactory-14063-thread-1-processing-n:127.0.0.1:60501_solr) [n:127.0.0.1:60501_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1617892 INFO (qtp596302720-22925) [n:127.0.0.1:60494_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> 1617915 DEBUG (ScheduledTrigger-14061-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> 1617980 INFO (zkCallback-14055-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> 1617980 INFO (zkCallback-14055-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> 1617980 INFO (zkCallback-14055-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> 1617980 INFO (zkCallback-14073-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> 1617980 INFO (zkCallback-14073-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> 1617980 INFO (qtp596302720-22925) [n:127.0.0.1:60494_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=1966
[junit4] 2> 1617980 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1617984 INFO (qtp596302720-22993) [n:127.0.0.1:60494_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> 1617986 INFO (qtp596302720-22923) [n:127.0.0.1:60494_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> 1617986 INFO (qtp1142173823-22929) [n:127.0.0.1:60501_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> 1617988 INFO (qtp1142173823-22928) [n:127.0.0.1:60501_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> 1617988 DEBUG (qtp1142173823-22927) [n:127.0.0.1:60501_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1617989 INFO (qtp1142173823-22927) [n:127.0.0.1:60501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 1617989 DEBUG (zkCallback-14055-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1617990 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1617990 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] 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> 1617990 DEBUG (OverseerAutoScalingTriggerThread-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@319cd0b2{/solr,null,AVAILABLE}
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@496ae5e4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60560}
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.s.Server Started @1556418ms
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60560}
[junit4] 2> 1617990 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T09:48:30.477211600Z
[junit4] 2> 1617990 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1617995 INFO (zkConnectionManagerCallback-14112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1617995 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1617996 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1618017 INFO (OverseerCollectionConfigSetProcessor-72085263852109829-127.0.0.1:60501_solr-n_0000000000) [n:127.0.0.1:60501_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> 1618094 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1618096 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@350100c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1618096 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@350100c2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1618099 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@71c7015e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1618099 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@71c7015e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1618100 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60481/solr
[junit4] 2> 1618102 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1618108 INFO (zkConnectionManagerCallback-14123-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1618108 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1618118 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1618131 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60560_solr as DOWN
[junit4] 2> 1618131 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1618131 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60560_solr
[junit4] 2> 1618131 INFO (zkCallback-14055-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1618131 INFO (zkCallback-14091-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1618131 INFO (zkCallback-14073-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1618131 INFO (zkCallback-14122-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1618137 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1618137 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_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> 1618163 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1618189 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60560.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1618198 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60560.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@26755773
[junit4] 2> 1618198 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [n:127.0.0.1:60560_solr ] o.a.s.m.r.SolrJmxReporter JMX mo
[...truncated too long message...]
rJmxReporter@b58517d: rootName = solr_60494, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6a99fe1a
[junit4] 2> 1662187 INFO (jetty-closer-14132-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1662275 INFO (jetty-closer-14132-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@496ae5e4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1662275 INFO (jetty-closer-14132-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@319cd0b2{/solr,null,UNAVAILABLE}
[junit4] 2> 1662281 INFO (jetty-closer-14132-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1662390 INFO (jetty-closer-14132-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72085263852109829-127.0.0.1:60501_solr-n_0000000000) closing
[junit4] 2> 1662393 INFO (jetty-closer-14132-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4ad3c68d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1662393 INFO (jetty-closer-14132-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5b4323ff{/solr,null,UNAVAILABLE}
[junit4] 2> 1662393 INFO (jetty-closer-14132-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1662520 INFO (jetty-closer-14132-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2d4d2181{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1662520 INFO (jetty-closer-14132-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19df298f{/solr,null,UNAVAILABLE}
[junit4] 2> 1662520 INFO (jetty-closer-14132-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1662526 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1662777 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 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> 26 /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> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1662787 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:60481
[junit4] 2> 1662787 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60481
[junit4] 2> 1662787 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60481
[junit4] 2> 1665203 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[7C1E5F7ED8AF67CD]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=7C1E5F7ED8AF67CD -Dtests.slow=true -Dtests.locale=fo-DK -Dtests.timezone=America/Argentina/Catamarca -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 49.5s J0 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([7C1E5F7ED8AF67CD:EF05170C86523CF9]: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\J0\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_7C1E5F7ED8AF67CD-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1982, maxMBSortInHeap=6.889010341678652, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@326afe30), locale=fo-DK, timezone=America/Argentina/Catamarca
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=100445968,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [OverriddenZkACLAndCredentialsProvidersTest, TestCustomSort, TestMissingGroups, TestJsonFacets, EnumFieldTest, TestHttpShardHandlerFactory, MergeStrategyTest, TestDynamicFieldResource, TestSolrCloudSnapshots, ConcurrentDeleteAndCreateCollectionTest, NotRequiredUniqueKeyTest, CreateCollectionCleanupTest, SystemLogListenerTest, TestHalfAndHalfDocValues, ExitableDirectoryReaderTest, EmbeddedSolrNoSerializeTest, XmlUpdateRequestHandlerTest, TestIndexSearcher, AnalysisAfterCoreReloadTest, TestFieldCacheWithThreads, TestDistribIDF, SolrIndexConfigTest, UUIDUpdateProcessorFallbackTest, UtilsToolTest, FieldMutatingUpdateProcessorTest, HighlighterWithoutStoredIdTest, TestPerFieldSimilarity, SimpleMLTQParserTest, TestSolrJacksonAnnotation, TestRecoveryHdfs, TestJoin, TestFilteredDocIdSet, HealthCheckHandlerTest, TestSolrConfigHandlerConcurrent, TestFieldCache, TermVectorComponentDistributedTest, SignificantTermsQParserPluginTest, MBeansHandlerTest, RequestHandlersTest, TestAnalyzeInfixSuggestions, PreAnalyzedFieldTest, RoutingToNodesWithPropertiesTest, TestNumericRangeQuery64, ParsingFieldUpdateProcessorsTest, SolrInfoBeanTest, TestUnInvertedFieldException, UpdateParamsTest, JWTAuthPluginIntegrationTest, DistributedSuggestComponentTest, TestRandomCollapseQParserPlugin, TestTextField, TestJettySolrRunner, HdfsTlogReplayBufferedWhileIndexingTest, DateFieldTest, TestExactStatsCacheCloud, DistributedFacetPivotLargeTest, PhrasesIdentificationComponentTest, TestIBSimilarityFactory, SpellCheckCollatorTest, LeaderElectionContextKeyTest, IndexSizeTriggerSizeEstimationTest, MultiSolrCloudTestCaseTest, TestStandardQParsers, TestBulkSchemaConcurrent, TestFacetMethods, HdfsRecoverLeaseTest, FileUtilsTest, XmlInterpolationTest, PKIAuthenticationIntegrationTest, TestReloadAndDeleteDocs, TestCorePropertiesReload, FullHLLTest, TestReqParamsAPI, RulesTest, TestDistribDocBasedVersion, TestTlogReplayVsRecovery, TestLeaderElectionZkExpiry, ChaosMonkeyNothingIsSafeWithPullReplicasTest, SmileWriterTest, TestSimExecutePlanAction, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestMaxTokenLenTokenizer, TestSha256AuthenticationProvider, UninvertDocValuesMergePolicyTest, TestCaffeineCache, BadIndexSchemaTest, TestScoreJoinQPScore, AtomicUpdatesTest, DeleteReplicaTest, UnloadDistributedZkTest, TestXmlQParser, LeaderFailoverAfterPartitionTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, OverseerRolesTest, OverseerTaskQueueTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, SplitShardTest, SystemCollectionCompatTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudRecovery, TestCloudSearcherWarming, TestRandomFlRTGCloud, TestRandomRequestDistribution, TestSizeLimitedDistributedMap, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestWaitForStateWithJettyShutdowns, AssignTest, AsyncCallRequestStatusResponseTest, CollectionsAPIAsyncDistributedZkTest, TestCollectionAPI, ComputePlanActionTest, NodeMarkersRegistrationTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [627/913 (1!)] on J0 in 49.61s, 1 test, 1 failure <<< FAILURES!
[...truncated 53393 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8865/consoleText
[repro] Revision: 338671e511b753955f7186e7063cd95824cdf4e0
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3156 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=7C1E5F7ED8AF67CD -Dtests.slow=true -Dtests.locale=fo-DK -Dtests.timezone=America/Argentina/Catamarca -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-15-ea+16) -
Build # 8866 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8866/
Java: 64bit/jdk-15-ea+16 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([598C9DD6EEAFF98:968381AF3017A4AC]: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:564)
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:832)
Build Log:
[...truncated 14900 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1402218 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-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> 1402220 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\data-dir-108-001
[junit4] 2> 1402220 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1402222 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1402222 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[598C9DD6EEAFF98]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1402224 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1402225 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001
[junit4] 2> 1402225 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1402227 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1402227 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer start zk server on port: 54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54540
[junit4] 2> 1402324 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54540
[junit4] 2> 1402328 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402337 INFO (zkConnectionManagerCallback-14674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402337 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402342 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402347 INFO (zkConnectionManagerCallback-14676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402348 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402349 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402353 INFO (zkConnectionManagerCallback-14678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402353 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1402464 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1402465 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c6e530b{/solr,null,AVAILABLE}
[junit4] 2> 1402466 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ca42e47{/solr,null,AVAILABLE}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7f8bbb6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54558}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.e.j.s.Server Started @1346137ms
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54558}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b78d429{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54557}
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.e.j.s.Server Started @1346137ms
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54557}
[junit4] 2> 1402468 ERROR (jetty-launcher-14679-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1402468 ERROR (jetty-launcher-14679-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:56.654505900Z
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1402468 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:56.654505900Z
[junit4] 2> 1402469 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402469 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402472 INFO (zkConnectionManagerCallback-14683-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402472 INFO (zkConnectionManagerCallback-14681-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1402472 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1402579 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1402581 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5396570c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402581 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5396570c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402584 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28b415ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402584 WARN (jetty-launcher-14679-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28b415ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402585 INFO (jetty-launcher-14679-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1402586 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402589 INFO (zkConnectionManagerCallback-14694-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402589 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402685 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1402686 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a5942fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402686 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a5942fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402690 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5fc63364[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402690 WARN (jetty-launcher-14679-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5fc63364[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1402692 INFO (jetty-launcher-14679-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1402694 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402696 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54558_solr
[junit4] 2> 1402697 INFO (zkConnectionManagerCallback-14707-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402697 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402697 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.Overseer Overseer (id=72086613484896261-127.0.0.1:54558_solr-n_0000000000) starting
[junit4] 2> 1402703 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54558_solr
[junit4] 2> 1402703 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:54558_solr
[junit4] 2> 1402704 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1402704 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1402705 INFO (zkCallback-14706-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1402705 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1402706 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1402706 WARN (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_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> 1402707 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:54557_solr as DOWN
[junit4] 2> 1402708 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1402708 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54557_solr
[junit4] 2> 1402709 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:54558_solr]
[junit4] 2> 1402709 INFO (zkCallback-14693-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1402709 INFO (zkCallback-14706-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1402709 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1402710 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1402710 DEBUG (ScheduledTrigger-14712-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1402712 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1402713 WARN (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_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> 1402736 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1402738 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1402758 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402763 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402766 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402766 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402767 INFO (jetty-launcher-14679-thread-1) [n:127.0.0.1:54558_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1402775 INFO (jetty-launcher-14679-thread-2) [n:127.0.0.1:54557_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.
[junit4] 2> 1402795 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1402796 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1402798 INFO (zkConnectionManagerCallback-14730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1402798 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1402799 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1402800 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54540/solr ready
[junit4] 2> 1402806 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1402812 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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> 1402814 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1402816 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_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> 1402817 DEBUG (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1402818 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 1402818 DEBUG (zkCallback-14693-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1402819 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1402820 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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> 1402820 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1402820 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1402820 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1402824 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1402825 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1402827 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402828 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402829 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402831 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402832 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402834 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402835 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402837 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402838 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402839 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402841 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402843 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402844 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402846 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402847 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402849 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402851 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402852 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402853 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402855 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402856 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402858 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402859 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402861 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402863 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402864 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402865 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402867 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402868 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402870 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402872 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402873 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402875 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402876 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402878 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402879 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402881 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402882 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402884 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402885 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1402887 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1402888 INFO (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1402990 WARN (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_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> 1402991 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1402995 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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> 1402998 INFO (qtp1900829492-23436) [n:127.0.0.1:54558_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> 1402999 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1403002 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403006 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_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> 1403009 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 442802402 sessionWrapper.createTime 442802392138700, this.sessionWrapper.createTime 442802392138700
[junit4] 2> 1403013 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54558/solr",
[junit4] 2> "node_name":"127.0.0.1:54558_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403016 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54557/solr",
[junit4] 2> "node_name":"127.0.0.1:54557_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403018 INFO (OverseerStateUpdate-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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:54557/solr",
[junit4] 2> "node_name":"127.0.0.1:54557_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1403217 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403217 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403217 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403235 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403235 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403235 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403268 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403268 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403268 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1403413 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403413 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403414 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54558.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403414 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403414 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54557.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1403428 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1403505 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403505 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403508 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403508 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403508 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403508 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403511 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403511 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403511 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403512 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403512 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403514 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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 1667405958558515200
[junit4] 2> 1403514 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403515 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403515 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_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> 1403516 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403516 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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 1667405958560612352
[junit4] 2> 1403516 INFO (searcherExecutor-14734-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:54557_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> 1403517 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403517 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_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> 1403519 INFO (searcherExecutor-14732-thread-1-processing-n:127.0.0.1:54558_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:54558_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> 1403520 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403520 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403520 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 1403520 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1403522 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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, core_node5=0}, version=1}
[junit4] 2> 1403522 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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> 1403537 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403537 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403541 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=2 timeoute in=9999ms
[junit4] 2> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403541 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403542 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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 1667405958587875328
[junit4] 2> 1403542 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1403542 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_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> 1403547 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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, core_node5=0}, version=2}
[junit4] 2> 1403547 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1403547 INFO (searcherExecutor-14736-thread-1-processing-n:127.0.0.1:54557_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:54557_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> 1403687 DEBUG (ScheduledTrigger-14712-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> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:54557/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=http://127.0.0.1:54557/solr START replicas=[http://127.0.0.1:54558/solr/testScheduledTrigger_shard1_replica_n1/, http://127.0.0.1:54557/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1404030 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n2 url=http://127.0.0.1:54557/solr DONE. We have no versions. sync failed.
[junit4] 2> 1404031 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_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> 1404031 INFO (qtp1900829492-23442) [n:127.0.0.1:54558_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1404032 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72086613484896262-core_node5-n_0000000000
[junit4] 2> 1404034 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:54557/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 1404134 INFO (zkCallback-14706-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> 1404135 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1404139 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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=946
[junit4] 2> 1404236 INFO (zkCallback-14706-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> 1404236 INFO (zkCallback-14706-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> 1404520 INFO (qtp1900829492-23440) [n:127.0.0.1:54558_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=1338
[junit4] 2> 1404529 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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=1347
[junit4] 2> 1404529 DEBUG (OverseerThreadFactory-14714-thread-1-processing-n:127.0.0.1:54558_solr) [n:127.0.0.1:54558_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1404530 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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> 1404625 INFO (zkCallback-14706-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> 1404625 INFO (zkCallback-14706-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> 1404625 INFO (zkCallback-14693-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> 1404625 INFO (zkCallback-14693-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> 1404626 INFO (qtp1438589166-23439) [n:127.0.0.1:54557_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=1853
[junit4] 2> 1404626 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1404626 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_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> 1404630 INFO (qtp1438589166-23433) [n:127.0.0.1:54557_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> 1404630 INFO (qtp1900829492-23436) [n:127.0.0.1:54558_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> 1404631 INFO (qtp1900829492-23438) [n:127.0.0.1:54558_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> 1404632 DEBUG (qtp1438589166-23441) [n:127.0.0.1:54557_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1404633 INFO (qtp1438589166-23441) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1404633 DEBUG (zkCallback-14693-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1404633 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 1404634 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1404634 DEBUG (OverseerAutoScalingTriggerThread-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1404634 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1404635 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@73971f88{/solr,null,AVAILABLE}
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@10f76023{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:54605}
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.s.Server Started @1348364ms
[junit4] 2> 1404636 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54605}
[junit4] 2> 1404637 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1404637 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-22T15:43:58.823324700Z
[junit4] 2> 1404638 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1404640 INFO (zkConnectionManagerCallback-14750-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1404640 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1404641 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1404662 DEBUG (ScheduledTrigger-14712-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> 1404745 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1404747 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@686336fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404747 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@686336fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404749 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@62dcd9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404749 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@62dcd9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1404750 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54540/solr
[junit4] 2> 1404751 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1404754 INFO (zkConnectionManagerCallback-14761-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1404754 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1404760 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1404763 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:54605_solr as DOWN
[junit4] 2> 1404764 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1404764 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54605_solr
[junit4] 2> 1404765 INFO (zkCallback-14693-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14706-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404765 INFO (zkCallback-14760-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1404767 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1404767 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_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> 1404773 INFO (OverseerCollectionConfigSetProcessor-72086613484896261-127.0.0.1:54558_solr-n_0000000000) [n:127.0.0.1:54558_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> 1404790 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1404807 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404817 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404818 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54605.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@746889ee
[junit4] 2> 1404818 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [n:127.0.0.1:54605_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001\tempDir-001\node3\.
[junit4] 2> 1404833 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 1404838 INFO (qtp1438589166-23437) [n:127.0.0.1:54557_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigg
[...truncated too long message...]
[junit4] 2> 1439582 INFO (jetty-closer-14770-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@10f76023{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@73971f88{/solr,null,UNAVAILABLE}
[junit4] 2> 1439641 INFO (jetty-closer-14770-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439712 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5b78d429{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439712 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2c6e530b{/solr,null,UNAVAILABLE}
[junit4] 2> 1439713 INFO (jetty-closer-14770-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439782 INFO (jetty-closer-14770-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72086613484896261-127.0.0.1:54558_solr-n_0000000000) closing
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7f8bbb6d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3ca42e47{/solr,null,UNAVAILABLE}
[junit4] 2> 1439783 INFO (jetty-closer-14770-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1439784 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1439929 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> 21 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 2 /solr/collections/testScheduledTrigger/leader_elect/shard1/election/72086613484896262-core_node5-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 15 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:54540
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54540
[junit4] 2> 1439932 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 54540
[junit4] 2> 1441339 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[598C9DD6EEAFF98]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=598C9DD6EEAFF98 -Dtests.slow=true -Dtests.locale=ko-KR -Dtests.timezone=Atlantic/Stanley -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 39.1s J3 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([598C9DD6EEAFF98:968381AF3017A4AC]: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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J3\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_598C9DD6EEAFF98-001
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=8, maxDocsPerChunk=1, blockShift=3), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=8, blockSize=3)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5abfe250), locale=ko-KR, timezone=Atlantic/Stanley
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea (64-bit)/cpus=6,threads=1,free=164628552,total=358162432
[junit4] 2> NOTE: All tests run in this JVM: [NoCacheHeaderTest, ClusterStateUpdateTest, TestNestedDocsSort, TestCollationField, DistributedUpdateProcessorTest, TestLRUStatsCacheCloud, TestCursorMarkWithoutUniqueKey, TestDynamicLoading, DistributedExpandComponentTest, ReindexCollectionTest, TestQueryingOnDownCollection, CursorPagingTest, DocValuesNotIndexedTest, BasicZkTest, AtomicUpdateProcessorFactoryTest, HighlighterMaxOffsetTest, ConfigSetsAPITest, SuggesterTest, FuzzySearchTest, BasicAuthStandaloneTest, TestInitParams, OverseerRolesTest, TestNumericTerms64, TestCryptoKeys, DataDrivenBlockJoinTest, SuggestComponentContextFilterQueryTest, XMLAtomicUpdateMultivalueTest, DocValuesMultiTest, TestExternalRoleRuleBasedAuthorizationPlugin, AddReplicaTest, TestSolrCloudWithHadoopAuthPlugin, MetricsHistoryIntegrationTest, SolrCoreCheckLockOnStartupTest, IndexSchemaTest, TestJsonFacetErrors, OutputWriterTest, CoreSorterTest, PathHierarchyTokenizerFactoryTest, LoggingHandlerTest, SimpleFacetsTest, MoveReplicaHDFSTest, JvmMetricsTest, SoftAutoCommitTest, TlogReplayBufferedWhileIndexingTest, ImplicitSnitchTest, HttpPartitionTest, TestGraphTermsQParserPlugin, TestSchemaVersionResource, CdcrBootstrapTest, TestExportTool, TestManagedSynonymGraphFilterFactory, TestCustomDocTransformer, TestJsonRequest, TestCloudPhrasesIdentificationComponent, TestLRUStatsCache, NodeMarkersRegistrationTest, OverseerTest, BinaryUpdateRequestHandlerTest, TestTrieFacet, ShardRoutingCustomTest, TestMaxScoreQueryParser, HdfsChaosMonkeySafeLeaderTest, TestComplexPhraseQParserPlugin, TestSolr4Spatial2, InfixSuggestersTest, AutoscalingHistoryHandlerTest, BlockCacheTest, SplitHandlerTest, TestSortByMinMaxFunction, ZkControllerTest, LeaderFailoverAfterPartitionTest, PrimUtilsTest, TestExportWriter, CollectionsAPIDistributedZkTest, TolerantUpdateProcessorTest, TemplateUpdateProcessorTest, TestTolerantUpdateProcessorRandomCloud, TestCoreContainer, TestSchemalessBufferedUpdates, DistributedFacetPivotSmallTest, TestFieldCacheSortRandom, SearchRateTriggerIntegrationTest, DistribCursorPagingTest, StressHdfsTest, TestReplicationHandlerBackup, TestManagedSchemaThreadSafety, TestAuthenticationFramework, CopyFieldTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestGroupingSearch, TestSimpleTrackingShardHandler, BasicDistributedZk2Test, ChaosMonkeySafeLeaderTest, ClusterStateMockUtilTest, ClusterStateTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DeleteStatusTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, PeerSyncReplicationTest, TestDistributedMap, TestDownShardTolerantSearch, TestDynamicFieldNamesIndexCorrectly, TestRequestForwarding, TestSolrCloudWithDelegationTokens, TestStressLiveNodes, SimpleCollectionCreateDeleteTest, TestRequestStatusCollectionAPI, HttpTriggerListenerTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, ScheduledMaintenanceTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [572/913 (1!)] on J3 in 39.15s, 1 test, 1 failure <<< FAILURES!
[...truncated 45995 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8866/consoleText
[repro] Revision: 78f4a5b8ff854861ac6ad17c27016e222463e54c
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=598C9DD6EEAFF98 -Dtests.slow=true -Dtests.locale=ko-KR -Dtests.timezone=Atlantic/Stanley -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...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...]