Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6959/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseG1GC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at
__randomizedtesting.SeedInfo.seed([644A706B4030B2C1:ADFF32C549577434]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:684)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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)
Build Log:
[...truncated 12634 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> 1405757 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\init-core-data-001
[junit4] 2> 1405758 WARN
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1405758 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1405759 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1405761 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001
[junit4] 2> 1405761 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1405762 INFO (Thread-2787) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1405762 INFO (Thread-2787) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1405765 ERROR (Thread-2787) [ ] 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> 1405861 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:62991
[junit4] 2> 1405872 INFO (jetty-launcher-1990-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1405872 INFO (jetty-launcher-1990-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1405872 INFO (jetty-launcher-1990-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@13671aa1{/solr,null,AVAILABLE}
[junit4] 2> 1405872 INFO (jetty-launcher-1990-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@59f731f6{/solr,null,AVAILABLE}
[junit4] 2> 1405876 INFO (jetty-launcher-1990-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@11ef251e{SSL,[ssl,
http/1.1]}{127.0.0.1:62996}
[junit4] 2> 1405876 INFO (jetty-launcher-1990-thread-2) [ ]
o.e.j.s.Server Started @1415865ms
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62996}
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@7db5cdb9{SSL,[ssl,
http/1.1]}{127.0.0.1:62995}
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.e.j.s.Server Started @1415865ms
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62995}
[junit4] 2> 1405877 ERROR (jetty-launcher-1990-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1405877 ERROR (jetty-launcher-1990-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-14T10:01:14.295714700Z
[junit4] 2> 1405877 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-14T10:01:14.295714700Z
[junit4] 2> 1405880 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1405880 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1405885 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1405887 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62991/solr
[junit4] 2> 1405887 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62991/solr
[junit4] 2> 1405890 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70004, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1405978 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1405978 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1405979 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62996_solr
[junit4] 2> 1405979 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62995_solr
[junit4] 2> 1405980 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=98826667568857093-127.0.0.1:62996_solr-n_0000000000) starting
[junit4] 2> 1405981 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1405981 INFO (zkCallback-2002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1405987 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 1405988 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62996_solr
[junit4] 2> 1405988 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1405990 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1405990 INFO (zkCallback-2002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1405990 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:62996_solr,
127.0.0.1:62995_solr]
[junit4] 2> 1405990 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 1405990 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1405992 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1405992 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1405992 DEBUG (ScheduledTrigger-6142-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:62996_solr]
[junit4] 2> 1406033 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62995.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406039 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62995.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406040 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62995.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406040 INFO (jetty-launcher-1990-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001\node1\.
[junit4] 2> 1406055 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62996.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406061 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62996.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406061 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62996.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406062 INFO (jetty-launcher-1990-thread-2) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001\node2\.
[junit4] 2> 1406087 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1406088 INFO
(SUITE-TriggerIntegrationTest-seed#[644A706B4030B2C1]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1406103 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testEventFromRestoredState
[junit4] 2> 1406104 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 2
[junit4] 2> 1406104 DEBUG (zkCallback-2002-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 1406104 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 1406106 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1406106 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1406107 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 1406143 INFO (qtp1458480948-12674) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1406144 INFO (qtp1458480948-12674) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1406166 INFO (qtp633299249-12668) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1406171 INFO (qtp1458480948-12681) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1406171 DEBUG (qtp1458480948-12674) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1406173 INFO (qtp1458480948-12674) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=43
[junit4] 2> 1406173 DEBUG (zkCallback-2002-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1406175 DEBUG (zkCallback-2002-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:62996_solr,
127.0.0.1:62995_solr]
[junit4] 2> 1406175 DEBUG (zkCallback-2002-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=10, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 1406175 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 1406177 INFO
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1406177 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1406178 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 1406178 DEBUG (ScheduledTrigger-6142-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1406178 DEBUG (ScheduledTrigger-6142-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr]
[junit4] 2> 1406180 INFO (qtp633299249-12685) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1406185 INFO (qtp633299249-12685) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1406187 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1406187 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ca08f03{/solr,null,AVAILABLE}
[junit4] 2> 1406188 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@151bd930{SSL,[ssl,
http/1.1]}{127.0.0.1:63032}
[junit4] 2> 1406188 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server Started @1416177ms
[junit4] 2> 1406189 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63032}
[junit4] 2> 1406189 ERROR
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 1406189 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.0.0
[junit4] 2> 1406189 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 1406189 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default
config dir: null
[junit4] 2> 1406189 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-14T10:01:14.607538700Z
[junit4] 2> 1406192 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1406200 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62991/solr
[junit4] 2> 1406212 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1406214 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1406217 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63032_solr
[junit4] 2> 1406219 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1406219 INFO (zkCallback-2002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1406219 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1406219 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1406285 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63032.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406294 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63032.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406294 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63032.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1406295 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001\node3\.
[junit4] 2> 1407180 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1407180 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1407180 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:63032_solr at time
1507975275598000000
[junit4] 2> 1408182 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1408182 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1408184 INFO
(OverseerCollectionConfigSetProcessor-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1409182 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1409182 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1410183 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1410183 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1411184 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1411184 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1412184 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1412184 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1413185 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1413185 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1414187 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1414187 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1415188 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1415188 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1416189 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1416189 DEBUG (ScheduledTrigger-6142-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1417190 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1417190 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr]
[junit4] 2> 1417190 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:63032_solr] added at times
[1507975275598000000]
[junit4] 2> 1417198 INFO (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1417201 INFO (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1417202 INFO (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 1417203 DEBUG (AutoscalingActionExecutor-6143-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14ed678608172f80Tepjs3xj058uej5utm7cbuzvyw",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1507975275598000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1507975275598000000],
[junit4] 2> "_enqueue_time_":1507975285608000000,
[junit4] 2> "nodeNames":["127.0.0.1:63032_solr"]}}
[junit4] 2> 1417208 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1417209 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@9fd2b56{/solr,null,AVAILABLE}
[junit4] 2> 1417210 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@31115d8a{SSL,[ssl,
http/1.1]}{127.0.0.1:63047}
[junit4] 2> 1417211 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server Started @1427200ms
[junit4] 2> 1417211 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63047}
[junit4] 2> 1417212 ERROR
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 1417212 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.0.0
[junit4] 2> 1417212 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 1417212 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default
config dir: null
[junit4] 2> 1417212 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-14T10:01:25.630978400Z
[junit4] 2> 1417218 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1417226 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec7000e, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1417230 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62991/solr
[junit4] 2> 1417235 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec7000f, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1417246 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1417248 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1417251 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63047_solr
[junit4] 2> 1417252 INFO (zkCallback-2002-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1417252 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1417252 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1417252 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1417253 INFO (zkCallback-2026-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1417303 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63047.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1417311 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63047.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1417311 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63047.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1417312 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001\node4\.
[junit4] 2> 1418206 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1418206 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1418206 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:63047_solr at time
1507975286624000000
[junit4] 2> 1419209 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1419209 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1420213 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1420213 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1421214 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1421214 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1422217 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1422217 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1423218 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1423218 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1424219 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1424219 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1425220 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1425220 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1426220 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1426221 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1427221 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1427221 DEBUG (ScheduledTrigger-6142-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:62996_solr, 127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1427351 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@11ef251e{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 1427352 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1124433558
[junit4] 2> 1427352 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 1427352 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@307f26d7: rootName =
solr_62996, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@15f120b6
[junit4] 2> 1427367 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 1427367 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6db0c6a3: rootName =
solr_62996, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7eafbeab
[junit4] 2> 1427377 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1427377 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5e0e33f9: rootName =
solr_62996, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3768efc3
[junit4] 2> 1427381 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1427381 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1427382 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.Overseer Overseer
(id=98826667568857093-127.0.0.1:62996_solr-n_0000000000) closing
[junit4] 2> 1427382 INFO
(OverseerStateUpdate-98826667568857093-127.0.0.1:62996_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62996_solr
[junit4] 2> 1427384 WARN
(OverseerAutoScalingTriggerThread-98826667568857093-127.0.0.1:62996_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1427384 DEBUG
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 1427387 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62995_solr
[junit4] 2> 1427387 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1427387 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1427387 INFO (zkCallback-2026-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1427390 INFO (zkCallback-2001-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1427390 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98826667568857094-127.0.0.1:62995_solr-n_0000000001) starting
[junit4] 2> 1427398 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 1427399 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 4
[junit4] 2> 1427401 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr, 127.0.0.1:62995_solr]
[junit4] 2> 1427401 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger
instantiated with properties: {event=nodeAdded, waitFor=10,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 1427402 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr, 127.0.0.1:62995_solr]
[junit4] 2> 1427402 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion -1
[junit4] 2> 1427402 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 1427405 INFO
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1427406 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.NodeAddedTrigger Adding node from marker path:
127.0.0.1:63047_solr
[junit4] 2> 1427406 DEBUG (ScheduledTrigger-6163-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:62995_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1427406 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 1427408 DEBUG (ScheduledTrigger-6163-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1427408 DEBUG (ScheduledTrigger-6163-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:62995_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1428408 DEBUG (ScheduledTrigger-6163-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:62995_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1428410 DEBUG (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1428410 DEBUG (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:62995_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1428410 DEBUG (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:63047_solr] added at times
[1507975286624000000]
[junit4] 2> 1428418 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1428421 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1428422 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 1428423 DEBUG (AutoscalingActionExecutor-6164-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14ed6788994a9800Tepjs3xj058uej5utm7cbuzvyy",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1507975286624000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1507975286624000000],
[junit4] 2> "_enqueue_time_":1507975296828000000,
[junit4] 2> "nodeNames":["127.0.0.1:63047_solr"]}}
[junit4] 2> 1428888 WARN (zkCallback-2002-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1428889 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@59f731f6{/solr,null,UNAVAILABLE}
[junit4] 2> 1428890 INFO
(TEST-TriggerIntegrationTest.testEventFromRestoredState-seed#[644A706B4030B2C1])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testEventFromRestoredState
[junit4] 2> 1428923 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTriggerRestoreState
[junit4] 2> 1428924 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 5
[junit4] 2> 1428924 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 1428925 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 1428929 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1428929 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1428929 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 1428931 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1428934 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@690e6585{/solr,null,AVAILABLE}
[junit4] 2> 1428935 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@13ae498e{SSL,[ssl,
http/1.1]}{127.0.0.1:63062}
[junit4] 2> 1428936 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server Started @1438924ms
[junit4] 2> 1428936 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63062}
[junit4] 2> 1428936 ERROR
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 1428936 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.0.0
[junit4] 2> 1428936 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 1428936 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default
config dir: null
[junit4] 2> 1428937 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-10-14T10:01:37.355647500Z
[junit4] 2> 1428941 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1428946 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70012, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1428949 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62991/solr
[junit4] 2> 1428952 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70013, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1428960 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1428962 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1428964 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:63062_solr
[junit4] 2> 1428966 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1428966 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1428967 INFO (zkCallback-2036-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1428967 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1428967 INFO (zkCallback-2026-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1429045 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63062.solr.node'
(registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1429053 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63062.solr.jvm'
(registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1429053 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63062.solr.jetty'
(registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@3c972b25
[junit4] 2> 1429054 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001\tempDir-001\node5\.
[junit4] 2> 1429114 INFO (qtp1670475006-12801) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1429115 INFO (qtp1670475006-12801) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1429134 INFO (qtp1596718554-12739) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1429137 INFO (qtp1670475006-12796) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1429140 INFO (qtp633299249-12668) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1429155 INFO (qtp1171051162-12765) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1429155 DEBUG (qtp1670475006-12801) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1429157 INFO (qtp1670475006-12801) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 1429157 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 1429158 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@13ae498e{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 1429158 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1481146995
[junit4] 2> 1429158 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 1429158 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@29d08bd9: rootName =
solr_63062, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1ca5fc7f
[junit4] 2> 1429159 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr, 127.0.0.1:63062_solr, 127.0.0.1:62995_solr]
[junit4] 2> 1429159 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 1429160 INFO
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1429160 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1429161 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 1429161 DEBUG (ScheduledTrigger-6163-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger
with currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:63032_solr,
127.0.0.1:63047_solr, 127.0.0.1:63062_solr]
[junit4] 2> 1429164 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 1429164 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ff9a79a: rootName =
solr_63062, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7eafbeab
[junit4] 2> 1429168 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1429168 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@63e1511f: rootName =
solr_63062, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3768efc3
[junit4] 2> 1429170 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1429170 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1429171 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70014, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1429171 INFO (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1429172 INFO (zkCallback-2026-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1429172 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1429172 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1430163 DEBUG (ScheduledTrigger-6163-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger
with currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1430163 DEBUG (ScheduledTrigger-6163-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:63062_solr
[junit4] 2> 1430674 WARN (zkCallback-2036-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1430674 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@690e6585{/solr,null,UNAVAILABLE}
[junit4] 2> 1431166 DEBUG (ScheduledTrigger-6163-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger
with currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1432167 DEBUG (ScheduledTrigger-6163-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger
with currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1432192 INFO (qtp1596718554-12736) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1432195 INFO (qtp1596718554-12736) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1432198 INFO (qtp1596718554-12739) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1432202 INFO (qtp633299249-12684) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1432206 INFO (qtp1171051162-12766) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1432206 DEBUG (qtp1596718554-12736) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1432207 INFO (qtp1596718554-12736) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=23
[junit4] 2> 1432207 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
7
[junit4] 2> 1432208 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr, 127.0.0.1:62995_solr]
[junit4] 2> 1432209 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 7
[junit4] 2> 1432210 INFO
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1432211 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.NodeLostTrigger Adding lost node from marker path:
127.0.0.1:63062_solr
[junit4] 2> 1432211 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1432211 DEBUG (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_restore_trigger
with currently live nodes: [127.0.0.1:62995_solr, 127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1432211 DEBUG (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost
nodes: [127.0.0.1:63062_solr]
[junit4] 2> 1432211 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion 7
[junit4] 2> 1432215 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1432216 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1432217 INFO (ScheduledTrigger-6163-thread-3) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 1432217 DEBUG (AutoscalingActionExecutor-6164-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14ed678bdc0def40Tepjs3xj058uej5utm7cbuzvz0",
[junit4] 2> "source":"node_lost_restore_trigger",
[junit4] 2> "eventTime":1507975300629000000,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1507975300629000000],
[junit4] 2> "_enqueue_time_":1507975300629000000,
[junit4] 2> "nodeNames":["127.0.0.1:63062_solr"]}}
[junit4] 2> 1432217 INFO (AutoscalingActionExecutor-6164-thread-1) [ ]
o.a.s.c.ActionThrottle The last action attempt started 3793ms ago.
[junit4] 2> 1432217 INFO (AutoscalingActionExecutor-6164-thread-1) [ ]
o.a.s.c.ActionThrottle Throttling action attempts - waiting for 1206ms
[junit4] 2> 1433423 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[644A706B4030B2C1])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTriggerRestoreState
[junit4] 2> 1433435 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testContinueTriggersOnOverseerRestart
[junit4] 2> 1433436 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 8
[junit4] 2> 1433436 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
8
[junit4] 2> 1433436 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 8
[junit4] 2> 1433437 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1433437 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1433438 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8,
lastZnodeVersion 8
[junit4] 2> 1433439 INFO (qtp633299249-12684) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1433444 INFO (qtp633299249-12684) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1433450 INFO (qtp1171051162-12763) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1433451 INFO (qtp1171051162-12763) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62991/solr ready
[junit4] 2> 1433453 INFO (qtp1596718554-12737) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1433456 INFO (qtp633299249-12667) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1433459 INFO (qtp1171051162-12767) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0
QTime=0
[junit4] 2> 1433459 DEBUG (qtp1171051162-12763) [ ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1433459 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70018, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1433460 INFO (qtp1171051162-12763) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 1433460 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
9
[junit4] 2> 1433462 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr, 127.0.0.1:62995_solr]
[junit4] 2> 1433462 DEBUG (zkCallback-2001-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated
with properties: {event=nodeAdded, waitFor=2, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 1433462 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 9
[junit4] 2> 1433464 INFO
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1433465 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1433465 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9,
lastZnodeVersion 9
[junit4] 2> 1433465 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7db5cdb9{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 1433466 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2086877362
[junit4] 2> 1433466 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 1433466 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7a332ff2: rootName =
solr_62995, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@12d40e29
[junit4] 2> 1433466 DEBUG (ScheduledTrigger-6163-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1433467 DEBUG (ScheduledTrigger-6163-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:62995_solr,
127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1433472 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 1433473 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c3d54b8: rootName =
solr_62995, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7eafbeab
[junit4] 2> 1433478 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1433478 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@55f1a4ac: rootName =
solr_62995, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@3768efc3
[junit4] 2> 1433479 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1433479 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 1433479 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.Overseer Overseer
(id=98826667568857094-127.0.0.1:62995_solr-n_0000000001) closing
[junit4] 2> 1433481 WARN
(OverseerAutoScalingTriggerThread-98826667568857094-127.0.0.1:62995_solr-n_0000000001)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1433481 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 1433481 INFO
(OverseerStateUpdate-98826667568857094-127.0.0.1:62995_solr-n_0000000001) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62995_solr
[junit4] 2> 1433483 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15f1a549ec70006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1433483 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63032_solr
[junit4] 2> 1433483 INFO (zkCallback-2008-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1433484 INFO (zkCallback-2026-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1433484 INFO (zkCallback-2016-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98826667568857100-127.0.0.1:63032_solr-n_0000000002) starting
[junit4] 2> 1433486 INFO (zkCallback-2016-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1433502 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 1433503 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 10
[junit4] 2> 1433504 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr]
[junit4] 2> 1433504 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger
instantiated with properties: {event=nodeAdded, waitFor=2, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 1433505 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:63047_solr,
127.0.0.1:63032_solr]
[junit4] 2> 1433505 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10,
lastZnodeVersion -1
[junit4] 2> 1433505 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 10
[junit4] 2> 1433507 INFO
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 1433507 DEBUG
(OverseerAutoScalingTriggerThread-98826667568857100-127.0.0.1:63032_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 10,
lastZnodeVersion 10
[junit4] 2> 1433507 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1433508 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1433508 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1434509 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1434510 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1434510 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1434984 WARN (zkCallback-2001-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1434984 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@13671aa1{/solr,null,UNAVAILABLE}
[junit4] 2> 1435510 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1435510 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1435510 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1436510 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1436510 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1436511 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1437518 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1437520 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1437526 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1438521 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1438521 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1438528 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1439522 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1439522 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1439529 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1440522 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1440522 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1440529 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1441523 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1441523 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1441530 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1442524 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1442524 DEBUG (ScheduledTrigger-6175-thread-4) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1442531 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1443528 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1443528 DEBUG (ScheduledTrigger-6175-thread-3) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1443532 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1444529 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1444529 DEBUG (ScheduledTrigger-6175-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:63032_solr,
127.0.0.1:63047_solr]
[junit4] 2> 1444532 DEBUG (ScheduledTrigger-6175-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: [127.0.0.1:63032_solr, 127.0.0.1:63047_solr]
[junit4] 2> 1444988 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1444988 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51d9050e{/solr,null,AVAILABLE}
[junit4] 2> 1444989 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@16ed00c8{SSL,[ssl,
http/1.1]}{127.0.0.1:63148}
[junit4] 2> 1444989 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.e.j.s.Server Started @1454978ms
[junit4] 2> 1444989 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=63148}
[junit4] 2> 1444990 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 1444990 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.0.0
[junit4] 2> 1444990 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 1444990 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[644A706B4030B2C1])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default
config dir: null
[junit4] 2> 1444990 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[64
[...truncated too long message...]
junit4] 2> 7 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/overseer/queue-work
[junit4] 2> 6 /solr/overseer/queue
[junit4] 2> 3 /solr/autoscaling/events/node_added_trigger
[junit4] 2> 2 /solr/autoscaling/events/node_lost_trigger
[junit4] 2> 2 /solr/autoscaling/events/node_added_trigger1
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_644A706B4030B2C1-001
[junit4] 2> Oct 14, 2017 10:03:09 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 6 leaked
thread(s).
[junit4] 2> 1522113 WARN (ScheduledTrigger-6255-thread-2) [ ]
o.a.s.c.a.TriggerBase Exception getting trigger state
'/autoscaling/triggerState/.auto_add_replicas'
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for
/autoscaling/triggerState/.auto_add_replicas
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1102)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:323)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:320)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:320)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.TriggerBase.restoreState(TriggerBase.java:91)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$ScheduledTrigger.run(ScheduledTriggers.java:460)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[junit4] 2> at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1522115 WARN (ScheduledTrigger-6255-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger NodeLostTrigger ran but was already closed
[junit4] 2> 1522115 ERROR (ScheduledTrigger-6255-thread-2) [ ]
o.a.s.c.a.NodeLostTrigger Unexpected exception in NodeLostTrigger
[junit4] 2> java.lang.RuntimeException: Trigger has been closed
[junit4] 2> at
org.apache.solr.cloud.autoscaling.NodeLostTrigger.run(NodeLostTrigger.java:228)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$ScheduledTrigger.run(ScheduledTriggers.java:468)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[junit4] 2> at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1522115 WARN (ScheduledTrigger-6255-thread-2) [ ]
o.a.s.c.a.TriggerBase Exception updating trigger state
'/autoscaling/triggerState/.auto_add_replicas'
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for
/autoscaling/triggerState/.auto_add_replicas
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1102)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:323)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:320)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:320)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.TriggerBase.saveState(TriggerBase.java:73)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$ScheduledTrigger.run(ScheduledTriggers.java:475)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[junit4] 2> at
java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=207, maxMBSortInHeap=7.065267488924229,
sim=RandomSimilarity(queryNorm=false): {}, locale=pt-GQ,
timezone=America/Godthab
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=260423136,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [SmileWriterTest,
TestFastLRUCache, EchoParamsTest, TestReRankQParserPlugin, MBeansHandlerTest,
TestMiniSolrCloudClusterSSL, TestCrossCoreJoin, TestCloudManagedSchema,
TestScoreJoinQPNoScore, TestReloadDeadlock, TestRTGBase, TestMacros,
LeaderFailoverAfterPartitionTest, TestFileDictionaryLookup,
HttpSolrCallGetCoreTest, TestLocalFSCloudBackupRestore,
TestDeleteCollectionOnDownNodes, TestJsonFacetRefinement, ZkFailoverTest,
TestSearchPerf, TestDownShardTolerantSearch, TestLuceneMatchVersion,
CacheHeaderTest, DistributedMLTComponentTest, TestSolrConfigHandler,
RequestLoggingTest, TermsComponentTest, TestFastWriter, TestCoreAdminApis,
TestSolrCLIRunExample, TestComponentsName, BlockJoinFacetSimpleTest,
TestHdfsCloudBackupRestore, CoreAdminCreateDiscoverTest,
TestSolrCloudWithSecureImpersonation, TestCloudJSONFacetJoinDomain,
TestDefaultStatsCache, BadIndexSchemaTest, TestZkChroot,
ClassificationUpdateProcessorIntegrationTest, LukeRequestHandlerTest,
LoggingHandlerTest, BasicAuthStandaloneTest, LeaderElectionIntegrationTest,
OverseerStatusTest, TestManagedResource, MoveReplicaTest, ZkNodePropsTest,
TestDistributedMissingSort, JvmMetricsTest, TriLevelCompositeIdRoutingTest,
TestDynamicLoading, TestFieldSortValues, TestUseDocValuesAsStored,
TestJsonRequest, ConjunctionSolrSpellCheckerTest, TestCorePropertiesReload,
FullHLLTest, PolyFieldTest, TestSubQueryTransformerCrossCore,
TestSolrFieldCacheBean, DocumentAnalysisRequestHandlerTest,
TestDelegationWithHadoopAuth, TestInfoStreamLogging,
DistributedFacetPivotSmallAdvancedTest, TestJettySolrRunner, TestStressLucene,
TestSystemIdResolver, ReplicationFactorTest, NodeMutatorTest,
ComputePlanActionTest, TestPrepRecovery, TestUniqueKeyFieldResource,
HdfsAutoAddReplicasIntegrationTest, TestJoin, DocumentBuilderTest,
SuggestComponentTest, TemplateUpdateProcessorTest, SpatialRPTFieldTypeTest,
DistributedVersionInfoTest, TestConfig, TestXmlQParser, JavabinLoaderTest,
TestSolrJ, PreAnalyzedFieldManagedSchemaCloudTest, TestCollapseQParserPlugin,
TestCustomDocTransformer, DataDrivenBlockJoinTest, TestReload, BasicZkTest,
TestReqParamsAPI, BasicDistributedZk2Test, TestRandomRequestDistribution,
TestUnifiedSolrHighlighter, TestShardHandlerFactory, NodeAddedTriggerTest,
RuleEngineTest, TestTlogReplica, HdfsChaosMonkeySafeLeaderTest,
TestRetrieveFieldsOptimizer, HdfsNNFailoverTest, HdfsSyncSliceTest,
PreAnalyzedFieldTest, TestRuleBasedAuthorizationPlugin, TestConfigOverlay,
SolrMetricReporterTest, SolrIndexMetricsTest, CopyFieldTest, DOMUtilTest,
TestSortByMinMaxFunction, TestExpandComponent, TestLegacyTerms,
TestLegacyFieldReuse, TestDocumentBuilder, TestSchemaManager,
SchemaVersionSpecificBehaviorTest, TestUninvertingReader, TestRemoteStreaming,
DistributedTermsComponentTest, StatsReloadRaceTest, DeleteShardTest,
SolrCmdDistributorTest, SimpleMLTQParserTest, CheckHdfsIndexTest,
DeleteNodeTest, TestRawResponseWriter, HttpPartitionTest,
TestHighFrequencyDictionaryFactory, TestEmbeddedSolrServerConstructors,
QueryResultKeyTest, TestRangeQuery, TestStreamBody,
StatelessScriptUpdateProcessorFactoryTest, TestCollationFieldDocValues,
SolrCLIZkUtilsTest, OverseerModifyCollectionTest, RollingRestartTest,
TestLRUStatsCache, TestSolrDeletionPolicy1, FileBasedSpellCheckerTest,
TestMinMaxOnMultiValuedField, TestWordDelimiterFilterFactory,
BlobRepositoryCloudTest, DocValuesMultiTest, TermVectorComponentTest,
TestRandomFaceting, TestCoreDiscovery, TestSolrIndexConfig, TestSchemaResource,
CollectionsAPISolrJTest, CachingDirectoryFactoryTest,
TestRandomCollapseQParserPlugin, ZkStateWriterTest, AtomicUpdatesTest,
BlockDirectoryTest, DebugComponentTest, MetricUtilsTest,
TestCloudSearcherWarming, TestAnalyzeInfixSuggestions, TriggerIntegrationTest]
[junit4] Completed [347/745 (1!)] on J1 in 116.40s, 10 tests, 1 failure <<<
FAILURES!
[...truncated 41122 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
