Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2312/
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testListeners
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([2136A2C8734F856E:CCF635D8CE2318B7]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testListeners(TriggerIntegrationTest.java:1053)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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
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.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication
Error Message:
Index: 0, Size: 0
Stack Trace:
java.lang.IndexOutOfBoundsException: Index: 0, Size: 0
at
__randomizedtesting.SeedInfo.seed([2136A2C8734F856E:357EF99D50483870]:0)
at java.util.ArrayList.rangeCheck(ArrayList.java:653)
at java.util.ArrayList.get(ArrayList.java:429)
at
org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
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
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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12744 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/init-core-data-001
[junit4] 2> 651519 WARN
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=15 numCloses=15
[junit4] 2> 651519 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 651520 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 651521 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 651521 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001
[junit4] 2> 651521 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 651522 INFO (Thread-2957) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 651522 INFO (Thread-2957) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 651524 ERROR (Thread-2957) [ ] 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> 651622 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:56871
[junit4] 2> 651624 INFO (zkConnectionManagerCallback-4139-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 651630 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 651635 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 651636 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 651636 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 651636 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 651636 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@67e51850{/solr,null,AVAILABLE}
[junit4] 2> 651640 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 651640 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 651640 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 651641 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@522992b5{/solr,null,AVAILABLE}
[junit4] 2> 651641 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@646b5fa0{SSL,[ssl,
http/1.1]}{127.0.0.1:56690}
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.e.j.s.Server Started @654189ms
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56690}
[junit4] 2> 651642 ERROR (jetty-launcher-4136-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 651642 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T16:38:00.511Z
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2dc06888{SSL,[ssl,
http/1.1]}{127.0.0.1:46101}
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.e.j.s.Server Started @654191ms
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46101}
[junit4] 2> 651643 ERROR (jetty-launcher-4136-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 651643 INFO (zkConnectionManagerCallback-4141-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 651643 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T16:38:00.512Z
[junit4] 2> 651644 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 651644 INFO (zkConnectionManagerCallback-4143-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652331 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 652336 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10455c741cf0001, likely client has closed socket
[junit4] 2> 652341 INFO (jetty-launcher-4136-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56871/solr
[junit4] 2> 652341 INFO (jetty-launcher-4136-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56871/solr
[junit4] 2> 652344 INFO (zkConnectionManagerCallback-4149-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652344 INFO (zkConnectionManagerCallback-4151-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652346 INFO
(zkConnectionManagerCallback-4155-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652346 INFO
(zkConnectionManagerCallback-4154-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652455 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 652455 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:56690_solr
[junit4] 2> 652456 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.Overseer Overseer
(id=73277808235708422-127.0.0.1:56690_solr-n_0000000000) starting
[junit4] 2> 652457 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 652458 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46101_solr
[junit4] 2> 652466 INFO
(zkCallback-4153-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 652477 INFO
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 652513 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56690_solr
[junit4] 2> 652513 INFO
(zkCallback-4153-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 652516 INFO
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 652528 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 652531 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 652531 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:56690_solr, 127.0.0.1:46101_solr]
[junit4] 2> 652531 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 652531 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 652544 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 652544 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 652548 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 652614 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56690.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652615 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46101.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652650 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46101.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652650 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46101.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652651 INFO (jetty-launcher-4136-thread-2)
[n:127.0.0.1:46101_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001/node2/.
[junit4] 2> 652653 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56690.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652653 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56690.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652656 INFO (jetty-launcher-4136-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001/node1/.
[junit4] 2> 652694 INFO (zkConnectionManagerCallback-4161-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652696 INFO (zkConnectionManagerCallback-4165-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652697 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 652697 INFO
(SUITE-TriggerIntegrationTest-seed#[2136A2C8734F856E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56871/solr ready
[junit4] 2> 652722 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTriggerRestoreState
[junit4] 2> 652722 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 652723 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 652723 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 2
[junit4] 2> 652727 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 652727 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 652728 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 652729 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 652730 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 652730 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 652730 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 652730 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@294bb8db{/solr,null,AVAILABLE}
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@2d52a46e{SSL,[ssl,
http/1.1]}{127.0.0.1:38382}
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.Server Started @655278ms
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38382}
[junit4] 2> 652731 ERROR
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.0.0
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 652731 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T16:38:01.600Z
[junit4] 2> 652734 INFO (zkConnectionManagerCallback-4167-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652735 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 652741 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56871/solr
[junit4] 2> 652742 INFO (zkConnectionManagerCallback-4171-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652744 INFO
(zkConnectionManagerCallback-4173-thread-1-processing-n:127.0.0.1:38382_solr)
[n:127.0.0.1:38382_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 652748 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 652749 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 652751 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38382_solr
[junit4] 2> 652756 INFO
(zkCallback-4153-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 652756 INFO
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 652756 INFO (zkCallback-4164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 652757 INFO
(zkCallback-4172-thread-1-processing-n:127.0.0.1:38382_solr)
[n:127.0.0.1:38382_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 652809 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38382.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652819 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38382.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652819 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38382.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 652820 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[n:127.0.0.1:38382_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001/node3/.
[junit4] 2> 652916 INFO (qtp1385507505-12870) [n:127.0.0.1:38382_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 652935 INFO (qtp1419071156-12818) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 652938 INFO (qtp1500030909-12807) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 652938 DEBUG (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 652939 INFO (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=44
[junit4] 2> 652939 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 652939 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:56690_solr, 127.0.0.1:46101_solr, 127.0.0.1:38382_solr]
[junit4] 2> 652939 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 652940 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2d52a46e{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 652940 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2069808969
[junit4] 2> 652940 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 652940 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6258f746: rootName =
solr_38382, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@54c4d6f7
[junit4] 2> 652941 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 652941 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 3
[junit4] 2> 652947 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 652947 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2e8afa17: rootName =
solr_38382, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7456463e
[junit4] 2> 652948 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 652948 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 652951 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 652951 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2ada23af: rootName =
solr_38382, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1e05e42f
[junit4] 2> 652952 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:38382_solr
[junit4] 2> 652952 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 652952 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 652952 INFO (zkCallback-4164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 652952 INFO
(zkCallback-4153-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 652953 INFO
(zkCallback-4172-thread-1-processing-n:127.0.0.1:38382_solr)
[n:127.0.0.1:38382_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 652953 INFO
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 652953 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@294bb8db{/solr,null,UNAVAILABLE}
[junit4] 2> 652953 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 653942 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 653942 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node:
127.0.0.1:38382_solr
[junit4] 2> 654465 INFO (qtp1419071156-12817) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 654467 INFO (qtp1500030909-12810) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 654468 DEBUG (qtp1419071156-12819) [n:127.0.0.1:46101_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 654468 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 654469 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:56690_solr, 127.0.0.1:46101_solr]
[junit4] 2> 654469 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 654469 INFO (qtp1419071156-12819) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 654479 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 654479 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Adding lost node from
marker path: 127.0.0.1:38382_solr
[junit4] 2> 654479 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 654479 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 654479 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing
registered processor for lost nodes: [127.0.0.1:38382_solr]
[junit4] 2> 654479 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"4225e05c1f979bTbofhf7ny2uz4xbcdebdpqu1yw",
[junit4] 2> "source":"node_lost_restore_trigger",
[junit4] 2> "eventTime":18618994011379611,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18618994011379611],
[junit4] 2> "nodeNames":["127.0.0.1:38382_solr"]}}
[junit4] 2> 654480 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 654480 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_lost_restore_trigger]
[junit4] 2> 654480 INFO (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 654486 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"4225e05c1f979bTbofhf7ny2uz4xbcdebdpqu1yw",
[junit4] 2> "source":"node_lost_restore_trigger",
[junit4] 2> "eventTime":18618994011379611,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18618994011379611],
[junit4] 2> "_enqueue_time_":18618994012499732,
[junit4] 2> "nodeNames":["127.0.0.1:38382_solr"]}}
[junit4] 2> 654486 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[2136A2C8734F856E])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTriggerRestoreState
[junit4] 2> 654499 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_lost_restore_trigger after 5000ms
[junit4] 2> 654499 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger
[junit4] 2> 654499 DEBUG
(zkCallback-4152-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 654499 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 654500 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 654501 INFO (qtp1419071156-12818) [n:127.0.0.1:46101_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 654502 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 15
ms for event id=4225e05c1f979bTbofhf7ny2uz4xbcdebdpqu1yw
[junit4] 2> 654505 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 654505 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 654505 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 654508 INFO (qtp1419071156-12818) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 654509 INFO (qtp1419071156-12820) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 654510 INFO (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 654510 DEBUG (qtp1419071156-12819) [n:127.0.0.1:46101_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 654510 INFO (qtp1419071156-12819) [n:127.0.0.1:46101_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 654516 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 654516 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:56690_solr, 127.0.0.1:46101_solr]
[junit4] 2> 654516 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 654517 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 654517 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 654518 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 654518 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 654521 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.e.j.s.AbstractConnector Stopped ServerConnector@2dc06888{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 654521 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=734533269
[junit4] 2> 654521 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 654522 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2a2234a9: rootName =
solr_46101, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@6d890382
[junit4] 2> 654534 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 654535 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6fdcfa4: rootName =
solr_46101, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7456463e
[junit4] 2> 654538 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 654538 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3bc0e8a0: rootName =
solr_46101, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1e05e42f
[junit4] 2> 654540 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:46101_solr
[junit4] 2> 654541 INFO
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 654541 INFO
(zkCallback-4153-thread-1-processing-n:127.0.0.1:46101_solr)
[n:127.0.0.1:46101_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 654542 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 654542 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 654546 INFO (zkCallback-4164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 654547 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@522992b5{/solr,null,UNAVAILABLE}
[junit4] 2> 654547 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.e.j.s.session Stopped scavenging
[junit4] 2> 655520 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 655520 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node:
127.0.0.1:46101_solr
[junit4] 2> 656503 INFO
(OverseerCollectionConfigSetProcessor-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 656521 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 657521 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 658521 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 658522 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing
registered processor for lost nodes: [127.0.0.1:46101_solr]
[junit4] 2> 658522 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"4225e09a30f95eTbofhf7ny2uz4xbcdebdpqu1yy",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":18618995052706142,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18618995052706142],
[junit4] 2> "nodeNames":["127.0.0.1:46101_solr"]}}
[junit4] 2> 658522 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_lost_trigger]
[junit4] 2> 658522 INFO (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 658523 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"4225e09a30f95eTbofhf7ny2uz4xbcdebdpqu1yy",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":18618995052706142,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18618995052706142],
[junit4] 2> "_enqueue_time_":18618998054400217,
[junit4] 2> "nodeNames":["127.0.0.1:46101_solr"]}}
[junit4] 2> 658524 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_lost_trigger after 5000ms
[junit4] 2> 658524 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=4225e09a30f95eTbofhf7ny2uz4xbcdebdpqu1yy
[junit4] 2> 658527 INFO (qtp1500030909-12811) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 658528 DEBUG (qtp1500030909-12810) [n:127.0.0.1:56690_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 658528 INFO (qtp1500030909-12810) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 658528 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 658529 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:56690_solr]
[junit4] 2> 658529 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 658529 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 658529 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 660529 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[2136A2C8734F856E]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
[junit4] 2> 660539 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 660539 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 8
[junit4] 2> 660539 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 660540 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 660541 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 660541 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 660541 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 660541 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 660542 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 660542 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 660542 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 660542 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@eb47e52{/solr,null,AVAILABLE}
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.AbstractConnector Started ServerConnector@39f81b5c{SSL,[ssl,
http/1.1]}{127.0.0.1:38517}
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.Server Started @663090ms
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38517}
[junit4] 2> 660543 ERROR
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 660543 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T16:38:09.412Z
[junit4] 2> 660544 INFO (zkConnectionManagerCallback-4177-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660545 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 660551 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56871/solr
[junit4] 2> 660552 INFO (zkConnectionManagerCallback-4181-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660554 INFO
(zkConnectionManagerCallback-4183-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660558 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 660560 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 660561 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38517_solr
[junit4] 2> 660562 INFO
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 660562 INFO (zkCallback-4164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 660564 INFO
(zkCallback-4182-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 660614 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38517.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660622 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38517.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660622 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_38517.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660624 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:38517_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001/node4/.
[junit4] 2> 660670 INFO (qtp1943903053-12904) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 660671 INFO (qtp1500030909-12807) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 660671 DEBUG (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 660672 INFO (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=19
[junit4] 2> 660672 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 9
[junit4] 2> 660675 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr]
[junit4] 2> 660675 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 660675 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 9
[junit4] 2> 660676 INFO (qtp1943903053-12902) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 660678 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 660679 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 660682 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 660682 DEBUG (ScheduledTrigger-2718-thread-4)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 660682 DEBUG (ScheduledTrigger-2718-thread-4)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 660684 INFO (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=5
[junit4] 2> 660684 DEBUG (qtp1943903053-12905) [n:127.0.0.1:38517_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 660684 INFO (qtp1943903053-12905) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 660685 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 10
[junit4] 2> 660685 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr]
[junit4] 2> 660685 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 660685 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr]
[junit4] 2> 660685 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 660685 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 10
[junit4] 2> 660686 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 660688 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T14:27:37-07:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 660689 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 660692 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger2
[junit4] 2> 660692 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 660692 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 660696 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 660696 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 660696 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 660696 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@161a0478{/solr,null,AVAILABLE}
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.AbstractConnector Started ServerConnector@5ce3b67f{SSL,[ssl,
http/1.1]}{127.0.0.1:44938}
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.Server Started @663245ms
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44938}
[junit4] 2> 660697 ERROR
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 660697 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-11T16:38:09.566Z
[junit4] 2> 660704 INFO (zkConnectionManagerCallback-4187-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660704 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 660712 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56871/solr
[junit4] 2> 660713 INFO (zkConnectionManagerCallback-4191-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660714 INFO
(zkConnectionManagerCallback-4193-thread-1-processing-n:127.0.0.1:44938_solr)
[n:127.0.0.1:44938_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 660720 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 660721 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 660723 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44938_solr
[junit4] 2> 660732 INFO
(zkCallback-4182-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 660732 INFO (zkCallback-4164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 660732 INFO
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 660735 INFO
(zkCallback-4192-thread-1-processing-n:127.0.0.1:44938_solr)
[n:127.0.0.1:44938_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 660793 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44938.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660802 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44938.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660802 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44938.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@559383cb
[junit4] 2> 660803 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[n:127.0.0.1:44938_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_2136A2C8734F856E-001/tempDir-001/node5/.
[junit4] 2> 661684 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 661684 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 661684 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:44938_solr at time 18619001216685402
[junit4] 2> 661684 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 firing registered processor for nodes:
[127.0.0.1:44938_solr] added at times [18619001216685402], now=18619001216847152
[junit4] 2> 661685 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"4225e20997d55aTbofhf7ny2uz4xbcdebdpqu1z0",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":18619001216685402,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18619001216685402],
[junit4] 2> "nodeNames":["127.0.0.1:44938_solr"]}}
[junit4] 2> 661685 DEBUG (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_trigger2, node_added_trigger1]
[junit4] 2> 661685 INFO (ScheduledTrigger-2718-thread-2)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 661685 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"4225e20997d55aTbofhf7ny2uz4xbcdebdpqu1z0",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":18619001216685402,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18619001216685402],
[junit4] 2> "_enqueue_time_":18619001217244184,
[junit4] 2> "nodeNames":["127.0.0.1:44938_solr"]}}
[junit4] 2> 661686 INFO
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest action executed
from node_added_trigger1
[junit4] 2> 661686 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger2 after 5000ms
[junit4] 2> 661686 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger1 after 5000ms
[junit4] 2> 661687 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=4225e20997d55aTbofhf7ny2uz4xbcdebdpqu1z0
[junit4] 2> 666687 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 666687 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 666687 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger2
[junit4] 2> 666687 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 666688 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:44938_solr at time 18619006220034395
[junit4] 2> 666688 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger2 firing registered processor for nodes:
[127.0.0.1:44938_solr] added at times [18619006220034395], now=18619006220075685
[junit4] 2> 666688 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"4225e333d0e15bTbofhf7ny2uz4xbcdebdpqu1z2",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":18619006220034395,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18619006220034395],
[junit4] 2> "nodeNames":["127.0.0.1:44938_solr"]}}
[junit4] 2> 666688 DEBUG (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_trigger2, node_added_trigger1]
[junit4] 2> 666688 INFO (ScheduledTrigger-2718-thread-3)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 666688 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"4225e333d0e15bTbofhf7ny2uz4xbcdebdpqu1z2",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":18619006220034395,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[18619006220034395],
[junit4] 2> "_enqueue_time_":18619006220280012,
[junit4] 2> "nodeNames":["127.0.0.1:44938_solr"]}}
[junit4] 2> 666689 INFO
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest last action at
1518367090555000000 current time = 1518367095557000000
[junit4] 2> real diff: 5002000000
[junit4] 2> min diff: 4998000000
[junit4] 2> 666689 INFO
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest action executed
from node_added_trigger2
[junit4] 2> 666689 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger2 after 5000ms
[junit4] 2> 666689 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger1 after 5000ms
[junit4] 2> 666689 DEBUG
(AutoscalingActionExecutor-2719-thread-1-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=4225e333d0e15bTbofhf7ny2uz4xbcdebdpqu1z2
[junit4] 2> 666693 INFO (qtp1943903053-12906) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666714 INFO (qtp2067546717-12930) [n:127.0.0.1:44938_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666718 INFO (qtp1500030909-12811) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666718 DEBUG (qtp1943903053-12904) [n:127.0.0.1:38517_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 666718 INFO (qtp1943903053-12904) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=28
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 11
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666719 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 666719 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 11
[junit4] 2> 666720 INFO (qtp1943903053-12902) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666721 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 666721 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 666722 DEBUG (ScheduledTrigger-2718-thread-1)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger1 with currently live nodes: 3
[junit4] 2> 666722 INFO (qtp2067546717-12927) [n:127.0.0.1:44938_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666723 INFO (qtp1500030909-12808) [n:127.0.0.1:56690_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 666723 DEBUG (qtp1943903053-12905) [n:127.0.0.1:38517_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 666724 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 12
[junit4] 2> 666724 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666724 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 666724 INFO (qtp1943903053-12905) [n:127.0.0.1:38517_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 666724 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666725 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666725 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}],
enabled=true}
[junit4] 2> 666725 DEBUG
(zkCallback-4152-thread-2-processing-n:127.0.0.1:56690_solr)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:56690_solr, 127.0.0.1:44938_solr]
[junit4] 2> 666725 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 12
[junit4] 2> 666726 INFO
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 666727 DEBUG
(OverseerAutoScalingTriggerThread-73277808235708422-127.0.0.1:56690_solr-n_0000000000)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 12, lastZnodeVersion 12
[junit4] 2> 666727 DEBUG (ScheduledTrigger-2718-thread-4)
[n:127.0.0.1:56690_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger2 with currently live nodes: 3
[junit4] 2> 666727 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.e.j.s.AbstractConnector Stopped ServerConnector@5ce3b67f{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 666727 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1553389149
[junit4] 2> 666727 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 666728 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4cf00804: rootName =
solr_44938, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@13e1428c
[junit4] 2> 666734 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 666734 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@75801dcd: rootName =
solr_44938, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@7456463e
[junit4] 2> 666738 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 666738 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[2136A2C8734F856E])
[...truncated too long message...]
Writer is ready to be used.
[junit4] 2> 1048507 INFO (indexFetcher-4253-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ffa6f55[collection1] main]
[junit4] 2> 1048509 INFO
(searcherExecutor-4249-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1ffa6f55[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c493)
Uninverting(_1(8.0.0):c1)))}
[junit4] 2> 1048532 INFO (qtp386817671-10868) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=494 status=0 QTime=0
[junit4] 2> 1048533 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.h.TestReplicationHandler Waited for 900ms and found 494 docs
[junit4] 2> 1048533 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending doTestStopPoll
[junit4] 2> 1048533 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@1b2f5481{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1048534 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1954106589
[junit4] 2> 1048534 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1048534 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@58bc02a8: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@415ecf4b
[junit4] 2> 1048539 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1048539 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f5ece2e: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@29e515c4
[junit4] 2> 1048542 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1048542 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@589635f: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@16703010
[junit4] 2> 1048543 INFO (coreCloseExecutor-4256-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@7abaabc2
[junit4] 2> 1048543 INFO (coreCloseExecutor-4256-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=2059054018
[junit4] 2> 1048543 INFO (coreCloseExecutor-4256-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d046ce5: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@22b6e8be
[junit4] 2> 1048553 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@30194921{/solr,null,UNAVAILABLE}
[junit4] 2> 1048553 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1048554 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2486c5f3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1048554 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1800635427
[junit4] 2> 1048554 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1048554 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4fe3b7ef: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@5f58d014
[junit4] 2> 1048561 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1048561 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6343192c: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@29e515c4
[junit4] 2> 1048568 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1048568 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5e50cd73: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@16703010
[junit4] 2> 1048573 INFO (coreCloseExecutor-4258-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1619414
[junit4] 2> 1048573 INFO (coreCloseExecutor-4258-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=23172116
[junit4] 2> 1048573 INFO (coreCloseExecutor-4258-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@14235abd: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@752afc72
[junit4] 2> 1048581 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5956d1a7{/solr,null,UNAVAILABLE}
[junit4] 2> 1048581 INFO
(TEST-TestReplicationHandler.doTestStopPoll-seed#[2136A2C8734F856E]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_2136A2C8734F856E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{name=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct),
newname=PostingsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=1689,
maxMBSortInHeap=7.53234471548032,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1282af94),
locale=en-GB, timezone=Asia/Hebron
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=233381640,total=531103744
[junit4] 2> NOTE: All tests run in this JVM:
[TestSimpleTrackingShardHandler, BasicZkTest, TestQueryWrapperFilter,
InfoHandlerTest, TimeRoutedAliasUpdateProcessorTest, SampleTest,
TestConfigOverlay, OverseerTest, TestPushWriter, BooleanFieldTest,
TestIntervalFaceting, TestLeaderElectionZkExpiry, HdfsRecoveryZkTest,
TestLRUCache, VersionInfoTest, CollectionStateFormat2Test, SolrInfoBeanTest,
TestMultiValuedNumericRangeQuery, TestDynamicLoading, RuleEngineTest,
TestStressRecovery, TestPullReplica, CreateRoutedAliasTest,
DistributedMLTComponentTest, DocValuesNotIndexedTest, SolrSlf4jReporterTest,
DistribJoinFromCollectionTest, RequiredFieldsTest,
LeaderInitiatedRecoveryOnShardRestartTest, TestInPlaceUpdatesDistrib,
TestFastLRUCache, IndexSchemaTest, OutOfBoxZkACLAndCredentialsProvidersTest,
TestBinaryResponseWriter, DeleteStatusTest, TestAnalyzeInfixSuggestions,
DirectUpdateHandlerOptimizeTest, TestXIncludeConfig, TestSolrJ,
TestHdfsBackupRestoreCore, ScriptEngineTest, MoveReplicaTest,
TestRandomRequestDistribution, TestRecovery, RequestLoggingTest,
BlockJoinFacetDistribTest, ReplicaListTransformerTest,
TestTolerantUpdateProcessorCloud, SyncSliceTest, TestNumericTokenStream,
TestUseDocValuesAsStored2, DistributedFacetPivotWhiteBoxTest, TestCharFilters,
TestSerializedLuceneMatchVersion, QueryElevationComponentTest,
TestManagedSynonymFilterFactory, ZkStateReaderTest, TermsComponentTest,
TestZkChroot, JsonLoaderTest, TestFieldCacheReopen, HighlighterTest,
PeerSyncTest, TestStressVersions, DeleteNodeTest, TestGenericDistributedQueue,
DocumentBuilderTest, BasicFunctionalityTest, TestPolicyCloud,
AsyncCallRequestStatusResponseTest, AddBlockUpdateTest,
TestShardHandlerFactory, GraphQueryTest, RemoteQueryErrorTest,
LeaderElectionContextKeyTest, TestFilteredDocIdSet, NodeLostTriggerTest,
DistributedSuggestComponentTest, TestCollectionAPI, TestScoreJoinQPNoScore,
SystemInfoHandlerTest, OverseerModifyCollectionTest, TestComputePlanAction,
TestReplicationHandler]
[junit4] Completed [287/776 (2!)] on J1 in 96.12s, 16 tests, 1 error, 1
skipped <<< FAILURES!
[...truncated 49925 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
