Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7621/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
6 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([9152BF4B08241A50:6A70176EDA8EF9C2]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:270)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling
Error Message:
Both triggers should have fired by now
Stack Trace:
java.lang.AssertionError: Both triggers should have fired by now
at
__randomizedtesting.SeedInfo.seed([9152BF4B08241A50:6A70176EDA8EF9C2]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testTriggerThrottling(TriggerIntegrationTest.java:222)
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:1742)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([9152BF4B08241A50]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([9152BF4B08241A50]:0)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([9152BF4B08241A50]:0)
FAILED:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([9152BF4B08241A50]:0)
Build Log:
[...truncated 13100 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_9152BF4B08241A50-001\init-core-data-001
[junit4] 2> 661047 WARN
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 661047 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 661050 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-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> 661050 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 661050 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
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.TriggerIntegrationTest_9152BF4B08241A50-001\tempDir-001
[junit4] 2> 661050 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 661050 INFO (Thread-1384) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 661050 INFO (Thread-1384) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 661054 ERROR (Thread-1384) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 661151 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:58530
[junit4] 2> 661154 INFO (zkConnectionManagerCallback-2337-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661158 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11
[junit4] 2> 661158 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5f9538d1{/solr,null,AVAILABLE}
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 661166 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 661167 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51b1e1bb{/solr,null,AVAILABLE}
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3b837325{HTTP/1.1,[http/1.1]}{127.0.0.1:58534}
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7e0c50a7{HTTP/1.1,[http/1.1]}{127.0.0.1:58537}
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.e.j.s.Server Started @654949ms
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.e.j.s.Server Started @654949ms
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58537}
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58534}
[junit4] 2> 661170 ERROR (jetty-launcher-2334-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 661170 ERROR (jetty-launcher-2334-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-16T00:41:12.502111100Z
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 661170 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-16T00:41:12.502111100Z
[junit4] 2> 661173 INFO (zkConnectionManagerCallback-2341-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661173 INFO (zkConnectionManagerCallback-2339-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661173 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 661173 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 661177 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00001, likely client has closed socket
[junit4] 2> 661177 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00002, likely client has closed socket
[junit4] 2> 661332 INFO (jetty-launcher-2334-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58530/solr
[junit4] 2> 661336 INFO (zkConnectionManagerCallback-2345-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661336 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00003, likely client has closed socket
[junit4] 2> 661338 INFO (zkConnectionManagerCallback-2347-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661398 INFO (jetty-launcher-2334-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58530/solr
[junit4] 2> 661400 INFO (zkConnectionManagerCallback-2353-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661402 INFO (zkConnectionManagerCallback-2355-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661417 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:58534_solr
[junit4] 2> 661417 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.Overseer Overseer
(id=72064846575697926-127.0.0.1:58534_solr-n_0000000000) starting
[junit4] 2> 661419 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58537_solr
[junit4] 2> 661422 INFO (zkCallback-2354-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 661423 INFO (zkCallback-2346-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 661424 INFO (zkConnectionManagerCallback-2362-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661426 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 661426 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:58530/solr ready
[junit4] 2> 661427 INFO
(OverseerStateUpdate-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[n:127.0.0.1:58534_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:58534_solr
[junit4] 2> 661429 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 661429 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 661429 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58534_solr
[junit4] 2> 661430 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 661430 DEBUG
(OverseerStateUpdate-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[n:127.0.0.1:58534_solr ] o.a.s.c.Overseer processMessage: queueSize: 0,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58534_solr"} current state version: 0
[junit4] 2> 661430 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 661430 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 661432 INFO (zkCallback-2346-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 661432 INFO (zkCallback-2354-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 661435 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:58537_solr]
[junit4] 2> 661435 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 661435 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 661436 INFO (zkCallback-2361-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 661438 DEBUG (ScheduledTrigger-3166-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 661450 INFO (zkConnectionManagerCallback-2367-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661451 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 661452 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:58530/solr ready
[junit4] 2> 661452 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 661457 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 661474 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58537.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661474 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58534.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661481 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58537.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661481 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58537.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661482 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58534.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661483 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58534.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 661483 INFO (jetty-launcher-2334-thread-2)
[n:127.0.0.1:58537_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.TriggerIntegrationTest_9152BF4B08241A50-001\tempDir-001\node2\.
[junit4] 2> 661483 INFO (jetty-launcher-2334-thread-1)
[n:127.0.0.1:58534_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.TriggerIntegrationTest_9152BF4B08241A50-001\tempDir-001\node1\.
[junit4] 2> 661524 INFO (zkConnectionManagerCallback-2371-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661528 INFO (zkConnectionManagerCallback-2376-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661529 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 661530 INFO
(SUITE-TriggerIntegrationTest-seed#[9152BF4B08241A50]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58530/solr ready
[junit4] 2> 661541 INFO (qtp755881306-7547) [n:127.0.0.1:58534_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 661545 INFO (qtp1801460530-7560) [n:127.0.0.1:58537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=2
[junit4] 2> 661546 DEBUG (qtp755881306-7551) [n:127.0.0.1:58534_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 661546 INFO (qtp755881306-7551) [n:127.0.0.1:58534_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 661547 DEBUG (zkCallback-2361-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 661550 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 661557 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 661562 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 661565 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testContinueTriggersOnOverseerRestart
[junit4] 2> 661567 INFO (zkConnectionManagerCallback-2378-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 661568 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf0000b, likely client has closed socket
[junit4] 2> 661569 INFO (qtp755881306-7552) [n:127.0.0.1:58534_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 661574 INFO (qtp755881306-7552) [n:127.0.0.1:58534_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 661577 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 661579 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 3
[junit4] 2> 661580 DEBUG (zkCallback-2361-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 661580 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 661580 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 661580 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 661580 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 661582 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 661586 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@3b837325{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 661587 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=366244104
[junit4] 2> 661587 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 661587 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@40260d80: rootName =
solr_58534, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@32b74ad2
[junit4] 2> 661593 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 661593 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1a6c7926: rootName =
solr_58534, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4370c20b
[junit4] 2> 661598 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 661598 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@69c09299: rootName =
solr_58534, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@50e464d1
[junit4] 2> 661603 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:58534_solr
[junit4] 2> 661604 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 661605 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.Overseer Overseer
(id=72064846575697926-127.0.0.1:58534_solr-n_0000000000) closing
[junit4] 2> 661606 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 661606 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 661606 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 661606 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 661606 WARN
(OverseerAutoScalingTriggerThread-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 661607 INFO
(OverseerStateUpdate-72064846575697926-127.0.0.1:58534_solr-n_0000000000)
[n:127.0.0.1:58534_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:58534_solr
[junit4] 2> 661607 INFO (zkCallback-2361-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661607 INFO (zkCallback-2346-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661607 INFO (zkCallback-2354-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661608 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 661608 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 661609 INFO (zkCallback-2375-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661609 INFO (zkCallback-2375-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661609 INFO (zkCallback-2366-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 661609 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00007, likely client has closed socket
[junit4] 2> 661610 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00006, likely client has closed socket
[junit4] 2> 661612 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5f9538d1{/solr,null,UNAVAILABLE}
[junit4] 2> 661613 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 661613 INFO (zkCallback-2346-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58537_solr
[junit4] 2> 661615 INFO (zkCallback-2346-thread-2)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer Overseer
(id=72064846575697924-127.0.0.1:58537_solr-n_0000000001) starting
[junit4] 2> 661621 INFO
(OverseerStateUpdate-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:58537_solr
[junit4] 2> 661621 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 661621 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 4
[junit4] 2> 661622 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion -1
[junit4] 2> 661622 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 661623 DEBUG
(OverseerStateUpdate-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58534_solr"} current state version: 0
[junit4] 2> 661625 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:58537_solr]
[junit4] 2> 661626 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 661626 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 661626 DEBUG (ScheduledTrigger-3179-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 662627 DEBUG (ScheduledTrigger-3179-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 663628 DEBUG (ScheduledTrigger-3179-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 664629 DEBUG (ScheduledTrigger-3179-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 665630 DEBUG (ScheduledTrigger-3179-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 666616 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z;
git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11
[junit4] 2> 666617 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 666617 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 666618 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 666618 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@129661a6{/solr,null,AVAILABLE}
[junit4] 2> 666619 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@2f3edd66{HTTP/1.1,[http/1.1]}{127.0.0.1:58576}
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.Server Started @660399ms
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58576}
[junit4] 2> 666620 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.0.0
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 666620 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-16T00:41:17.952284900Z
[junit4] 2> 666624 INFO (zkConnectionManagerCallback-2382-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666625 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 666631 DEBUG (ScheduledTrigger-3179-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 666845 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58530/solr
[junit4] 2> 666848 INFO (zkConnectionManagerCallback-2386-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666855 INFO (zkConnectionManagerCallback-2388-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666867 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 666874 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 666874 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58576_solr
[junit4] 2> 666876 DEBUG
(OverseerStateUpdate-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58576_solr"} current state version: 0
[junit4] 2> 666878 INFO (zkCallback-2366-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666878 INFO (zkCallback-2375-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666878 INFO (zkCallback-2375-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666879 INFO (zkCallback-2346-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666880 INFO (zkCallback-2387-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666927 INFO (zkConnectionManagerCallback-2395-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666929 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 666932 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:58530/solr ready
[junit4] 2> 666932 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 666959 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58576.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 666971 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58576.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 666972 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58576.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 666974 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[n:127.0.0.1:58576_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.TriggerIntegrationTest_9152BF4B08241A50-001\tempDir-001\node3\.
[junit4] 2> 667016 INFO (qtp1801460530-7553) [n:127.0.0.1:58537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 667028 INFO (qtp1801460530-7553) [n:127.0.0.1:58537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 667040 INFO (qtp1801460530-7559) [n:127.0.0.1:58537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=4
[junit4] 2> 667046 INFO (qtp1134314779-7646) [n:127.0.0.1:58576_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=3
[junit4] 2> 667047 DEBUG (qtp1801460530-7561) [n:127.0.0.1:58537_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 667048 INFO (qtp1801460530-7561) [n:127.0.0.1:58537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 667048 DEBUG (zkCallback-2366-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 667049 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 667053 INFO
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 667053 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR -
Initial livenodes: [127.0.0.1:58576_solr, 127.0.0.1:58537_solr]
[junit4] 2> 667053 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR
instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 667054 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@7e0c50a7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 667054 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1587712669
[junit4] 2> 667054 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 667055 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@392b0750: rootName =
solr_58537, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@2dd38107
[junit4] 2> 667056 DEBUG (ScheduledTrigger-3179-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 667056 DEBUG (ScheduledTrigger-3179-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 667059 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 667066 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 667066 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1de3adc3: rootName =
solr_58537, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4370c20b
[junit4] 2> 667072 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 667072 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@48cd6261: rootName =
solr_58537, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@50e464d1
[junit4] 2> 667074 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:58537_solr
[junit4] 2> 667075 DEBUG
(OverseerStateUpdate-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58537_solr"} current state version: 0
[junit4] 2> 667075 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 667075 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 667076 INFO (zkCallback-2346-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667076 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.Overseer Overseer
(id=72064846575697924-127.0.0.1:58537_solr-n_0000000001) closing
[junit4] 2> 667076 INFO (zkCallback-2366-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667077 INFO (zkCallback-2375-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667078 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool
executor now
[junit4] 2> 667078 INFO (zkCallback-2375-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667078 WARN
(OverseerAutoScalingTriggerThread-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 667078 INFO
(OverseerStateUpdate-72064846575697924-127.0.0.1:58537_solr-n_0000000001)
[n:127.0.0.1:58537_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:58537_solr
[junit4] 2> 667078 INFO (zkCallback-2387-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667079 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 667079 INFO (zkCallback-2394-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 667079 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 667079 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread
pool executor
[junit4] 2> 667080 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 667080 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 667082 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00004, likely client has closed socket
[junit4] 2> 667083 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@51b1e1bb{/solr,null,UNAVAILABLE}
[junit4] 2> 667083 INFO (zkCallback-2387-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58576_solr
[junit4] 2> 667083 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 667092 INFO (zkCallback-2387-thread-1)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer Overseer
(id=72064846575697934-127.0.0.1:58576_solr-n_0000000002) starting
[junit4] 2> 667098 INFO
(OverseerStateUpdate-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:58576_solr
[junit4] 2> 667099 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 5
[junit4] 2> 667100 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion -1
[junit4] 2> 667100 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 667102 DEBUG
(OverseerStateUpdate-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58537_solr"} current state version: 0
[junit4] 2> 667102 INFO
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 667102 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR -
Initial livenodes: [127.0.0.1:58576_solr]
[junit4] 2> 667102 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR
instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 667104 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:58576_solr]
[junit4] 2> 667104 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path:
127.0.0.1:58537_solr
[junit4] 2> 667105 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 667106 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 667106 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 667106 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 668108 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 668108 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 668108 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 669110 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 669110 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 669110 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 670111 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 670111 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 670111 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 671112 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 671112 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 671112 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 672113 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 672113 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 672113 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 673113 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 673113 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 673113 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 674114 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 674114 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 674114 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 675115 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 675115 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 675115 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 676178 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 676178 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 676178 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 677245 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z;
git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11
[junit4] 2> 677245 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 677245 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 677245 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 677246 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4f97d15d{/solr,null,AVAILABLE}
[junit4] 2> 677246 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@124c1884{HTTP/1.1,[http/1.1]}{127.0.0.1:58594}
[junit4] 2> 677247 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.e.j.s.Server Started @670868ms
[junit4] 2> 677247 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58594}
[junit4] 2> 677248 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 677248 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 677248 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.0.0
[junit4] 2> 677248 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 677248 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 677248 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-11-16T00:41:28.580789400Z
[junit4] 2> 677252 INFO (zkConnectionManagerCallback-2398-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 677255 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 677261 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00010, likely client has closed socket
[junit4] 2> 677287 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 677287 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 677287 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 677575 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58530/solr
[junit4] 2> 677578 INFO (zkConnectionManagerCallback-2402-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 677580 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf00011, likely client has closed socket
[junit4] 2> 677583 INFO (zkConnectionManagerCallback-2404-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 677606 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 677612 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 677613 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58594_solr
[junit4] 2> 677613 DEBUG
(OverseerStateUpdate-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58594_solr"} current state version: 0
[junit4] 2> 677616 INFO (zkCallback-2387-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 677616 INFO (zkCallback-2394-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 677616 INFO (zkCallback-2375-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 677616 INFO (zkCallback-2375-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 677617 INFO (zkCallback-2403-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 677649 INFO (zkConnectionManagerCallback-2411-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 677650 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 677651 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:58530/solr ready
[junit4] 2> 677651 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics
history in memory.
[junit4] 2> 677677 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58594.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 677689 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58594.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 677689 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58594.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@48edd08e
[junit4] 2> 677692 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] 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.TriggerIntegrationTest_9152BF4B08241A50-001\tempDir-001\node4\.
[junit4] 2> 678388 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 678388 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 678388 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 678388 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:58594_solr at time
110682015969200
[junit4] 2> 679491 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 679491 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 679491 DEBUG (ScheduledTrigger-3193-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 680592 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 680592 DEBUG (ScheduledTrigger-3193-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 680592 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR firing
registered processor for nodes: [127.0.0.1:58594_solr] added at times
[110682015969200], now=110685022132300
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"64aa2a3eafb0T4h0hagwo314q882dh91obx08u",
[junit4] 2> "source":"node_added_triggerCTOOR",
[junit4] 2> "eventTime":110682015969200,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[110682015969200],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "nodeNames":["127.0.0.1:58594_solr"]}}
[junit4] 2> 681695 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas,
.scheduled_maintenance, node_added_triggerCTOOR]
[junit4] 2> 681696 INFO (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 681696 DEBUG (ScheduledTrigger-3193-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerCTOOR - removing
marker path: /autoscaling/nodeAdded/127.0.0.1:58594_solr
[junit4] 2> 681697 DEBUG (AutoscalingActionExecutor-3194-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"64aa2a3eafb0T4h0hagwo314q882dh91obx08u",
[junit4] 2> "source":"node_added_triggerCTOOR",
[junit4] 2> "eventTime":110682015969200,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[110682015969200],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":110685022705600,
[junit4] 2> "nodeNames":["127.0.0.1:58594_solr"]}}
[junit4] 2> 681698 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[9152BF4B08241A50])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testContinueTriggersOnOverseerRestart
[junit4] 2> 681698 DEBUG (AutoscalingActionExecutor-3194-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 5000ms
[junit4] 2> 681698 DEBUG (AutoscalingActionExecutor-3194-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after
5000ms
[junit4] 2> 681698 DEBUG (AutoscalingActionExecutor-3194-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_triggerCTOOR after
5000ms
[junit4] 2> 681698 DEBUG (AutoscalingActionExecutor-3194-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event
id=64aa2a3eafb0T4h0hagwo314q882dh91obx08u
[junit4] 2> 681711 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEventQueue
[junit4] 2> 681714 INFO (zkConnectionManagerCallback-2414-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 681718 INFO (qtp1134314779-7642) [n:127.0.0.1:58576_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 681723 INFO (qtp1134314779-7642) [n:127.0.0.1:58576_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 681724 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 681726 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers -- removing trigger: .scheduled_maintenance
[junit4] 2> 681727 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers -- removing trigger: node_added_triggerCTOOR
[junit4] 2> 681728 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 6
[junit4] 2> 681729 DEBUG (zkCallback-2394-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 681729 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 681729 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 681729 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 681730 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 681730 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 681733 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2f3edd66{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 681733 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=103385518
[junit4] 2> 681734 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 681734 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@18fdc2e8: rootName =
solr_58576, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5f627ef4
[junit4] 2> 681747 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 681747 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5719bde6: rootName =
solr_58576, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4370c20b
[junit4] 2> 681756 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 681756 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@25f31c29: rootName =
solr_58576, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@50e464d1
[junit4] 2> 681758 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:58576_solr
[junit4] 2> 681759 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 681759 DEBUG
(OverseerStateUpdate-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58576_solr"} current state version: 0
[junit4] 2> 681760 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.Overseer Overseer
(id=72064846575697934-127.0.0.1:58576_solr-n_0000000002) closing
[junit4] 2> 681762 WARN
(OverseerAutoScalingTriggerThread-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 681762 INFO (zkCallback-2387-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681762 INFO (zkCallback-2394-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681762 INFO (zkCallback-2403-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681760 INFO
(OverseerStateUpdate-72064846575697934-127.0.0.1:58576_solr-n_0000000002)
[n:127.0.0.1:58576_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:58576_solr
[junit4] 2> 681762 INFO (zkCallback-2375-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 681762 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 681762 INFO (zkCallback-2375-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681766 INFO (zkCallback-2410-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681766 INFO (zkCallback-2375-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 681767 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10006989cf0000f, likely client has closed socket
[junit4] 2> 681768 INFO (zkCallback-2403-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58594_solr
[junit4] 2> 681769 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@129661a6{/solr,null,UNAVAILABLE}
[junit4] 2> 681769 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 681771 INFO (zkCallback-2403-thread-1)
[n:127.0.0.1:58594_solr ] o.a.s.c.Overseer Overseer
(id=72064846575697938-127.0.0.1:58594_solr-n_0000000003) starting
[junit4] 2> 681781 INFO
(OverseerStateUpdate-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[n:127.0.0.1:58594_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:58594_solr
[junit4] 2> 681781 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 681782 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 7
[junit4] 2> 681782 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion -1
[junit4] 2> 681783 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 7
[junit4] 2> 681783 DEBUG
(OverseerStateUpdate-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[n:127.0.0.1:58594_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:58576_solr"} current state version: 0
[junit4] 2> 681788 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:58594_solr]
[junit4] 2> 681790 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 681790 DEBUG
(OverseerAutoScalingTriggerThread-72064846575697938-127.0.0.1:58594_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion 7
[junit4] 2> 681790 DEBUG (ScheduledTrigger-3207-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 682892 DEBUG (ScheduledTrigger-3207-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 683993 DEBUG (ScheduledTrigger-3207-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 685093 DEBUG (ScheduledTrigger-3207-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 686194 DEBUG (ScheduledTrigger-3207-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 687194 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11
[junit4] 2> 687194 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 687194 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 687194 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 687194 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5da7f197{/solr,null,AVAILABLE}
[junit4] 2> 687195 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@13a6cedf{HTTP/1.1,[http/1.1]}{127.0.0.1:58673}
[junit4] 2> 687195 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.e.j.s.Server Started @679982ms
[junit4] 2> 687196 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[9152BF4B08241A50]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58673}
[junit4] 2> 687196 ERROR (TEST-TriggerIntegra
[...truncated too long message...]
nit4] 2> at
app//org.apache.solr.cloud.autoscaling.sim.TestSimDistributedQueue.testDistributedQueue(TestSimDistributedQueue.java:74)
[junit4] 2> at
app//org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue.testDistributedQueue(TestSimGenericDistributedQueue.java:37)
[junit4] 2> at
java.base@9.0.4/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[junit4] 2> at
java.base@9.0.4/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
java.base@9.0.4/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at
java.base@9.0.4/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at
app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:944)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at
app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at
java.base@9.0.4/java.lang.Thread.run(Thread.java:844)
[junit4] 2> Lab 15, 2018 10:39:16 EBONGI
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=14,
name=TEST-TestSimGenericDistributedQueue.testDistributedQueue-seed#[9152BF4B08241A50],
state=TIMED_WAITING, group=TGRP-TestSimGenericDistributedQueue]
[junit4] 2> 7356403 INFO
(TEST-TestSimGenericDistributedQueue.testDistributedQueue-seed#[9152BF4B08241A50])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDistributedQueue
[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.sim.TestSimGenericDistributedQueue_9152BF4B08241A50-001
[junit4] 2> Nov 16, 2018 5:39:16 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are:
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
chunkSize=27359, maxDocsPerChunk=119, blockSize=6),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY,
chunkSize=27359, blockSize=6)),
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@12372f92),
locale=teo-UG, timezone=America/Chihuahua
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4
(64-bit)/cpus=3,threads=1,free=59808456,total=97386496
[junit4] 2> NOTE: All tests run in this JVM:
[TestSimGenericDistributedQueue]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSimGenericDistributedQueue -Dtests.seed=9152BF4B08241A50
-Dtests.slow=true -Dtests.badapples=true -Dtests.locale=teo-UG
-Dtests.timezone=America/Chihuahua -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | TestSimGenericDistributedQueue (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>=
7200000 msec).
[junit4] > at
__randomizedtesting.SeedInfo.seed([9152BF4B08241A50]:0)
[junit4] Completed [9/10 (2!)] on J0 in 7355.10s, 1 test, 2 errors <<<
FAILURES!
[...truncated 20 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1567:
The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1092:
There were test failures: 10 suites, 41 tests, 1 suite-level error, 1 error, 1
failure [seed: 9152BF4B08241A50]
Total time: 122 minutes 47 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[repro] 1/5 failed:
org.apache.solr.cloud.autoscaling.sim.TestSimGenericDistributedQueue
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build
move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move
/y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST
lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig
move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=df5540acc99fe287758433701108303fedb2c5b6,
workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #7620
Recording test results
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting
ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
