Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1304/
Java: 64bit/jdk1.8.0_162 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeLostTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([EF1A808D2142DC2C:EBE4299F1B885EB6]: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.testNodeLostTrigger(TriggerIntegrationTest.java:521)
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.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([EF1A808D2142DC2C:5516B7027EAA0A63]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.junit.Assert.assertNull(Assert.java:562)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger(TriggerIntegrationTest.java:1575)
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 13830 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/init-core-data-001
[junit4] 2> 2739043 WARN
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=26 numCloses=26
[junit4] 2> 2739043 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2739044 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2739044 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2739044 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001
[junit4] 2> 2739045 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2739045 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2739045 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2739046 ERROR (Thread-3725) [ ] 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> 2739145 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:45989
[junit4] 2> 2739147 INFO (zkConnectionManagerCallback-6907-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739150 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2739150 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2db626e3{/solr,null,AVAILABLE}
[junit4] 2> 2739151 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@57a73f31{/solr,null,AVAILABLE}
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@323491e1{HTTP/1.1,[http/1.1]}{127.0.0.1:38517}
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@46f217ee{HTTP/1.1,[http/1.1]}{127.0.0.1:36127}
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-2) [ ]
o.e.j.s.Server Started @2740695ms
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-1) [ ]
o.e.j.s.Server Started @2740695ms
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38517}
[junit4] 2> 2739152 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36127}
[junit4] 2> 2739152 ERROR (jetty-launcher-6904-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2739152 ERROR (jetty-launcher-6904-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:31.404Z
[junit4] 2> 2739153 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:31.404Z
[junit4] 2> 2739154 INFO (zkConnectionManagerCallback-6909-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739154 INFO (zkConnectionManagerCallback-6911-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739154 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2739154 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2739160 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
0x10024c469160002, likely client has closed socket
[junit4] 2> 2739161 INFO (jetty-launcher-6904-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2739161 INFO (jetty-launcher-6904-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2739162 INFO (zkConnectionManagerCallback-6917-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739162 INFO (zkConnectionManagerCallback-6919-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739162 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
0x10024c469160004, likely client has closed socket
[junit4] 2> 2739163 INFO
(zkConnectionManagerCallback-6923-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739163 INFO
(zkConnectionManagerCallback-6922-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> 2739193 INFO (jetty-launcher-6904-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2739193 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2739193 INFO (jetty-launcher-6904-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:38517_solr
[junit4] 2> 2739194 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36127_solr
[junit4] 2> 2739194 INFO (jetty-launcher-6904-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.Overseer Overseer
(id=72098020033167366-127.0.0.1:38517_solr-n_0000000000) starting
[junit4] 2> 2739194 INFO
(zkCallback-6921-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... (0) -> (1)
[junit4] 2> 2739195 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2739197 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 2739197 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 2739197 INFO (jetty-launcher-6904-thread-2)
[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> 2739197 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:36127_solr]
[junit4] 2> 2739197 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 2739198 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 2739198 INFO
(zkCallback-6921-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> 2739198 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2739198 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2739198 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2739198 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 2739232 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36127.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739232 INFO (jetty-launcher-6904-thread-2)
[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@667ca7b5
[junit4] 2> 2739236 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36127.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739236 INFO (jetty-launcher-6904-thread-2)
[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@667ca7b5
[junit4] 2> 2739237 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36127.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739237 INFO (jetty-launcher-6904-thread-2)
[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@667ca7b5
[junit4] 2> 2739237 INFO (jetty-launcher-6904-thread-1)
[n:127.0.0.1:36127_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node1/.
[junit4] 2> 2739237 INFO (jetty-launcher-6904-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node2/.
[junit4] 2> 2739262 INFO (zkConnectionManagerCallback-6929-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739264 INFO (zkConnectionManagerCallback-6933-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739264 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2739265 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45989/solr ready
[junit4] 2> 2739278 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTriggerRestoreState
[junit4] 2> 2739278 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 2
[junit4] 2> 2739278 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 2739278 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 2739279 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2739279 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2739279 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2739281 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2739281 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2739281 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2739281 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2739281 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@34f6f64e{/solr,null,AVAILABLE}
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@4e3f1f3a{HTTP/1.1,[http/1.1]}{127.0.0.1:36151}
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.Server Started @2740824ms
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36151}
[junit4] 2> 2739282 ERROR
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.3.0
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2739282 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:31.533Z
[junit4] 2> 2739283 INFO (zkConnectionManagerCallback-6935-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739283 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2739288 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2739288 INFO (zkConnectionManagerCallback-6939-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739290 INFO
(zkConnectionManagerCallback-6941-thread-1-processing-n:127.0.0.1:36151_solr)
[n:127.0.0.1:36151_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2739293 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2739294 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2739294 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36151_solr
[junit4] 2> 2739295 INFO
(zkCallback-6921-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> 2739295 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2739295 INFO
(zkCallback-6940-thread-1-processing-n:127.0.0.1:36151_solr)
[n:127.0.0.1:36151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2739296 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2739342 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36151.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739348 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36151.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739348 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36151.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2739349 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36151_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node3/.
[junit4] 2> 2739388 INFO (qtp383053915-26505) [n:127.0.0.1:36127_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> 2739389 INFO (qtp1705258052-26554) [n:127.0.0.1:36151_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> 2739390 INFO (qtp87095301-26496) [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> 2739390 DEBUG (qtp383053915-26495) [n:127.0.0.1:36127_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2739391 INFO (qtp383053915-26495) [n:127.0.0.1:36127_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 2739391 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 2739392 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:36151_solr, 127.0.0.1:36127_solr]
[junit4] 2> 2739392 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 2739392 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@4e3f1f3a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2739392 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=656263138
[junit4] 2> 2739392 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 2739392 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@79a8977a: rootName =
solr_36151, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@22b51811
[junit4] 2> 2739392 INFO
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 2739393 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2739393 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2739393 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 3
[junit4] 2> 2739397 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 2739397 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@53b5517: rootName =
solr_36151, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4be16320
[junit4] 2> 2739400 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 2739400 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@26cc633: rootName =
solr_36151, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5a9c2ea7
[junit4] 2> 2739401 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:36151_solr
[junit4] 2> 2739401 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 2739401 INFO
(zkCallback-6921-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... (3) -> (2)
[junit4] 2> 2739401 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2739401 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2739401 INFO
(zkCallback-6940-thread-1-processing-n:127.0.0.1:36151_solr)
[n:127.0.0.1:36151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 2739401 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 2740394 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 2740394 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node:
127.0.0.1:36151_solr
[junit4] 2> 2740902 WARN
(zkCallback-6940-thread-1-processing-n:127.0.0.1:36151_solr)
[n:127.0.0.1:36151_solr ] o.a.s.c.ZkController Unable to register nodeLost
path for 127.0.0.1:36151_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:36151_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:786)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:365)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:365)
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:882)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$1(ZkStateReader.java:704)
[junit4] 2> at
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:703)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$900(ZkStateReader.java:72)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1090)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1085)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2740903 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@34f6f64e{/solr,null,UNAVAILABLE}
[junit4] 2> 2740903 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2741395 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 2742395 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 2742407 INFO (qtp383053915-26497) [n:127.0.0.1:36127_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> 2742408 INFO (qtp87095301-26506) [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> 2742409 DEBUG (qtp383053915-26503) [n:127.0.0.1:36127_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2742409 INFO (qtp383053915-26503) [n:127.0.0.1:36127_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 2742409 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 2742410 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:36127_solr]
[junit4] 2> 2742410 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 2742410 INFO
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 2742410 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Adding lost node from
marker path: 127.0.0.1:36151_solr
[junit4] 2> 2742410 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2742410 DEBUG (ScheduledTrigger-6655-thread-3)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_restore_trigger with currently live nodes: 2
[junit4] 2> 2742410 DEBUG (ScheduledTrigger-6655-thread-3)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing
registered processor for lost nodes: [127.0.0.1:36151_solr]
[junit4] 2> 2742410 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2742410 DEBUG (ScheduledTrigger-6655-thread-3)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"23106c50b7121T7xy4y2y3ikppcn0eq5yz1c6ng",
[junit4] 2> "source":"node_lost_restore_trigger",
[junit4] 2> "eventTime":616855098847521,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[616855098847521],
[junit4] 2> "nodeNames":["127.0.0.1:36151_solr"]}}
[junit4] 2> 2742411 DEBUG (ScheduledTrigger-6655-thread-3)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_lost_restore_trigger]
[junit4] 2> 2742411 INFO (ScheduledTrigger-6655-thread-3)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 2742411 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"23106c50b7121T7xy4y2y3ikppcn0eq5yz1c6ng",
[junit4] 2> "source":"node_lost_restore_trigger",
[junit4] 2> "eventTime":616855098847521,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[616855098847521],
[junit4] 2> "_enqueue_time_":616855099798071,
[junit4] 2> "nodeNames":["127.0.0.1:36151_solr"]}}
[junit4] 2> 2742412 INFO
(TEST-TriggerIntegrationTest.testNodeLostTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTriggerRestoreState
[junit4] 2> 2742412 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_lost_restore_trigger after 5000ms
[junit4] 2> 2742412 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0
ms for event id=23106c50b7121T7xy4y2y3ikppcn0eq5yz1c6ng
[junit4] 2> 2742420 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState
[junit4] 2> 2742420 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 5
[junit4] 2> 2742421 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 2742421 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 2742421 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2742421 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2742422 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 2742422 INFO (qtp383053915-26504) [n:127.0.0.1:36127_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> 2742423 INFO (qtp87095301-26508) [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> 2742423 DEBUG (qtp383053915-26505) [n:127.0.0.1:36127_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2742423 INFO (qtp383053915-26505) [n:127.0.0.1:36127_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2742424 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 2742424 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:38517_solr, 127.0.0.1:36127_solr]
[junit4] 2> 2742424 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_restore_trigger instantiated with properties: {event=nodeAdded,
waitFor=5, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 2742424 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 2742424 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2742424 INFO
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 2742425 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2742425 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 2742425 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 2742425 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 2742429 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2742429 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2742429 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6166244e{/solr,null,AVAILABLE}
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@5cfd8c80{HTTP/1.1,[http/1.1]}{127.0.0.1:43705}
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.e.j.s.Server Started @2743972ms
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43705}
[junit4] 2> 2742430 ERROR
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.3.0
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2742430 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:34.681Z
[junit4] 2> 2742431 INFO (zkConnectionManagerCallback-6945-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2742431 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2742436 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2742437 INFO (zkConnectionManagerCallback-6949-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2742438 INFO
(zkConnectionManagerCallback-6951-thread-1-processing-n:127.0.0.1:43705_solr)
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2742440 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2742441 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2742442 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43705_solr
[junit4] 2> 2742442 INFO
(zkCallback-6921-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> 2742442 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2742442 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2742443 INFO
(zkCallback-6950-thread-1-processing-n:127.0.0.1:43705_solr)
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2742477 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43705.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2742483 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43705.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2742483 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43705.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2742484 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:43705_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node4/.
[junit4] 2> 2743425 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 2743425 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2743425 DEBUG (ScheduledTrigger-6655-thread-2)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:43705_solr at time 616856114159470
[junit4] 2> 2744010 INFO (qtp383053915-26505) [n:127.0.0.1:36127_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> 2744012 INFO (qtp87095301-26612) [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> 2744012 INFO (qtp1046744417-26588) [n:127.0.0.1:43705_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> 2744013 DEBUG (qtp87095301-26508) [n:127.0.0.1:38517_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2744013 INFO (qtp87095301-26508) [n:127.0.0.1:38517_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 2744013 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 2744013 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:43705_solr, 127.0.0.1:38517_solr, 127.0.0.1:36127_solr]
[junit4] 2> 2744014 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_restore_trigger instantiated with properties: {event=nodeAdded,
waitFor=0, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 2744014 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 2744014 INFO
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 2744015 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Adding node from
marker path: 127.0.0.1:43705_solr
[junit4] 2> 2744015 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2744015 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 2744015 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 2744015 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 2744015 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_restore_trigger firing registered processor for nodes:
[127.0.0.1:43705_solr] added at times [616856703638674], now=616856703943048
[junit4] 2> 2744015 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"2310724b29c92T7xy4y2y3ikppcn0eq5yz1c6ni",
[junit4] 2> "source":"node_added_restore_trigger",
[junit4] 2> "eventTime":616856703638674,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[616856703638674],
[junit4] 2> "nodeNames":["127.0.0.1:43705_solr"]}}
[junit4] 2> 2744015 DEBUG (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_restore_trigger]
[junit4] 2> 2744016 INFO (ScheduledTrigger-6655-thread-1)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 2744016 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"2310724b29c92T7xy4y2y3ikppcn0eq5yz1c6ni",
[junit4] 2> "source":"node_added_restore_trigger",
[junit4] 2> "eventTime":616856703638674,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[616856703638674],
[junit4] 2> "_enqueue_time_":616856704166377,
[junit4] 2> "nodeNames":["127.0.0.1:43705_solr"]}}
[junit4] 2> 2744016 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[EF1A808D2142DC2C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTriggerRestoreState
[junit4] 2> 2744016 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_restore_trigger after 5000ms
[junit4] 2> 2744016 DEBUG
(AutoscalingActionExecutor-6656-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0
ms for event id=2310724b29c92T7xy4y2y3ikppcn0eq5yz1c6ni
[junit4] 2> 2744033 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSearchRate
[junit4] 2> 2744034 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 8
[junit4] 2> 2744034 DEBUG
(zkCallback-6921-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 2744034 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 2744034 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 2744034 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 2744034 DEBUG
(OverseerAutoScalingTriggerThread-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 2744035 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2744035 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2744035 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2744035 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2744035 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@40fb4cb{/solr,null,AVAILABLE}
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@23d45cca{HTTP/1.1,[http/1.1]}{127.0.0.1:36153}
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server Started @2745578ms
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36153}
[junit4] 2> 2744036 ERROR
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2744036 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:36.287Z
[junit4] 2> 2744037 INFO (zkConnectionManagerCallback-6955-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744037 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2744042 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2744042 INFO (zkConnectionManagerCallback-6959-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744044 INFO
(zkConnectionManagerCallback-6961-thread-1-processing-n:127.0.0.1:36153_solr)
[n:127.0.0.1:36153_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744046 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 2744047 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2744048 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36153_solr
[junit4] 2> 2744048 INFO
(zkCallback-6921-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... (3) -> (4)
[junit4] 2> 2744048 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2744048 INFO
(zkCallback-6950-thread-1-processing-n:127.0.0.1:43705_solr)
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 2744048 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 2744052 INFO
(zkCallback-6960-thread-1-processing-n:127.0.0.1:36153_solr)
[n:127.0.0.1:36153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 2744085 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36153.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744101 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36153.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744101 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36153.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744102 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:36153_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node5/.
[junit4] 2> 2744132 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2744133 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2744133 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2744133 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2744133 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@647f32b5{/solr,null,AVAILABLE}
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6246cb30{HTTP/1.1,[http/1.1]}{127.0.0.1:45615}
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server Started @2745676ms
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45615}
[junit4] 2> 2744134 ERROR
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2744134 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:36.385Z
[junit4] 2> 2744135 INFO (zkConnectionManagerCallback-6965-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744135 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2744140 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2744140 INFO (zkConnectionManagerCallback-6969-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744142 INFO
(zkConnectionManagerCallback-6971-thread-1-processing-n:127.0.0.1:45615_solr)
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744144 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2744147 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2744148 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45615_solr
[junit4] 2> 2744149 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2744149 INFO
(zkCallback-6950-thread-1-processing-n:127.0.0.1:43705_solr)
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 2744149 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 2744149 INFO
(zkCallback-6960-thread-1-processing-n:127.0.0.1:36153_solr)
[n:127.0.0.1:36153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 2744149 INFO
(zkCallback-6921-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... (4) -> (5)
[junit4] 2> 2744149 INFO
(zkCallback-6970-thread-1-processing-n:127.0.0.1:45615_solr)
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 2744189 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45615.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744194 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45615.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744195 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45615.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744195 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45615_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node6/.
[junit4] 2> 2744230 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2744231 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2744231 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2744231 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2744231 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@30aed886{/solr,null,AVAILABLE}
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5d92f45a{HTTP/1.1,[http/1.1]}{127.0.0.1:45997}
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.e.j.s.Server Started @2745774ms
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45997}
[junit4] 2> 2744232 ERROR
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2744232 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-05T18:01:36.483Z
[junit4] 2> 2744233 INFO (zkConnectionManagerCallback-6975-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744234 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2744239 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45989/solr
[junit4] 2> 2744239 INFO (zkConnectionManagerCallback-6979-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744241 INFO
(zkConnectionManagerCallback-6981-thread-1-processing-n:127.0.0.1:45997_solr)
[n:127.0.0.1:45997_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2744244 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 2744244 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2744245 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45997_solr
[junit4] 2> 2744246 INFO
(zkCallback-6921-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... (5) -> (6)
[junit4] 2> 2744246 INFO
(zkCallback-6970-thread-1-processing-n:127.0.0.1:45615_solr)
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 2744246 INFO
(zkCallback-6960-thread-1-processing-n:127.0.0.1:36153_solr)
[n:127.0.0.1:36153_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 2744246 INFO
(zkCallback-6950-thread-1-processing-n:127.0.0.1:43705_solr)
[n:127.0.0.1:43705_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 2744246 INFO (zkCallback-6932-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
[junit4] 2> 2744246 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 2744247 INFO
(zkCallback-6980-thread-1-processing-n:127.0.0.1:45997_solr)
[n:127.0.0.1:45997_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (6)
[junit4] 2> 2744282 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45997.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744288 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45997.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744289 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45997.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2744289 INFO
(TEST-TriggerIntegrationTest.testSearchRate-seed#[EF1A808D2142DC2C])
[n:127.0.0.1:45997_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node7/.
[junit4] 2> 2744329 INFO (qtp383053915-26503) [n:127.0.0.1:36127_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&name=collection1&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2744330 INFO
(OverseerThreadFactory-6657-thread-1-processing-n:127.0.0.1:38517_solr)
[n:127.0.0.1:38517_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 2744434 INFO
(OverseerStateUpdate-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36127/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2744435 INFO
(OverseerStateUpdate-72098020033167366-127.0.0.1:38517_solr-n_0000000000)
[n:127.0.0.1:38517_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:45615/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2744637 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2744637 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2744638 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2744638 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2744740 INFO
(zkCallback-6970-thread-1-processing-n:127.0.0.1:45615_solr)
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 2744740 INFO
(zkCallback-6920-thread-1-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 2744740 INFO
(zkCallback-6970-thread-2-processing-n:127.0.0.1:45615_solr)
[n:127.0.0.1:45615_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 2744740 INFO
(zkCallback-6920-thread-2-processing-n:127.0.0.1:36127_solr)
[n:127.0.0.1:36127_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [6])
[junit4] 2> 2745646 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2745646 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2745652 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2745652 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2745658 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2745658 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2745658 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using
configuration from collection collection1, trusted=true
[junit4] 2> 2745658 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using
configuration from collection collection1, trusted=true
[junit4] 2> 2745659 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36127.solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2745659 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45615.solr.core.collection1.shard1.replica_n2' (registry
'solr.core.collection1.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@667ca7b5
[junit4] 2> 2745659 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2745659 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2745659 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node1/collection1_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/]
[junit4] 2> 2745659 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node6/collection1_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001/tempDir-001/node6/./collection1_shard1_replica_n2/data/]
[junit4] 2> 2745688 INFO (qtp383053915-26580) [n:127.0.0.1:36127_solr
c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2745688 INFO (qtp1327182550-26641) [n:127.0.0.1:45615_solr
c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update
[...truncated too long message...]
.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from
client sessionid 0x10024c469160033, likely client has closed socket
[junit4] 2> 2942766 INFO (jetty-closer-6905-thread-3) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2942766 INFO (jetty-closer-6905-thread-9) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5b67e3d2{/solr,null,UNAVAILABLE}
[junit4] 2> 2942766 INFO (jetty-closer-6905-thread-9) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2942766 INFO (jetty-closer-6905-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@26b937ae{/solr,null,UNAVAILABLE}
[junit4] 2> 2942766 INFO (jetty-closer-6905-thread-8) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5ecdaf30{/solr,null,UNAVAILABLE}
[junit4] 2> 2942767 INFO (jetty-closer-6905-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2942767 INFO (jetty-closer-6905-thread-8) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2944260 WARN
(zkCallback-7026-thread-1-processing-n:127.0.0.1:41743_solr)
[n:127.0.0.1:41743_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2944260 WARN
(zkCallback-7088-thread-1-processing-n:127.0.0.1:34543_solr)
[n:127.0.0.1:34543_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2944260 INFO (jetty-closer-6905-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@14b3f888{/solr,null,UNAVAILABLE}
[junit4] 2> 2944260 INFO (jetty-closer-6905-thread-10) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@86e6251{/solr,null,UNAVAILABLE}
[junit4] 2> 2944260 INFO (jetty-closer-6905-thread-4) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2944261 INFO (jetty-closer-6905-thread-10) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2944261 INFO (jetty-closer-6905-thread-13) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3964214c{/solr,null,UNAVAILABLE}
[junit4] 2> 2944261 INFO (jetty-closer-6905-thread-13) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2944267 INFO (jetty-closer-6905-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@48f27ba2{/solr,null,UNAVAILABLE}
[junit4] 2> 2944268 INFO (jetty-closer-6905-thread-5) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2944268 ERROR
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
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> 2944268 INFO
(SUITE-TriggerIntegrationTest-seed#[EF1A808D2142DC2C]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45989 45989
[junit4] 2> 2949408 INFO (Thread-3725) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:45989 45989
[junit4] 2> 2949408 WARN (Thread-3725) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 37 /solr/collections/collection1/terms/shard1
[junit4] 2> 22 /solr/clusterprops.json
[junit4] 2> 22 /solr/aliases.json
[junit4] 2> 21 /solr/security.json
[junit4] 2> 8 /solr/configs/conf
[junit4] 2> 2 /solr/collections/testMetricTrigger/terms/shard1
[junit4] 2> 2 /solr/collections/testMetricTrigger/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 102 /solr/collections/collection1/state.json
[junit4] 2> 22 /solr/clusterstate.json
[junit4] 2> 13 /solr/collections/testMetricTrigger/state.json
[junit4] 2> 6 /solr/autoscaling.json
[junit4] 2> 3
/solr/overseer_elect/election/72098020033167398-127.0.0.1:42739_solr-n_0000000011
[junit4] 2> 3
/solr/collections/collection1/leader_elect/shard1/election/72098020033167377-core_node8-n_0000000003
[junit4] 2> 3
/solr/collections/collection1/leader_elect/shard1/election/72098020033167380-core_node4-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167420-127.0.0.1:42721_solr-n_0000000018
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167414-127.0.0.1:34543_solr-n_0000000016
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167365-127.0.0.1:36127_solr-n_0000000001
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167417-127.0.0.1:42713_solr-n_0000000017
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167380-127.0.0.1:45615_solr-n_0000000005
[junit4] 2> 2
/solr/collections/collection1/leader_elect/shard1/election/72098020033167365-core_node3-n_0000000001
[junit4] 2> 2
/solr/overseer_elect/election/72098020033167389-127.0.0.1:41369_solr-n_0000000008
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/live_nodes
[junit4] 2> 22 /solr/collections
[junit4] 2> 6 /solr/overseer/queue-work
[junit4] 2> 6 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 6 /solr/overseer/queue
[junit4] 2> 6 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/node_lost_triggerMR
[junit4] 2> 2 /solr/autoscaling/events/node_added_triggerCTOOR
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_EF1A808D2142DC2C-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
x_s=FSTOrd50}, docValues:{_version_=DocValuesFormat(name=Lucene70)},
maxPointsInLeafNode=1332, maxMBSortInHeap=5.622511947733528,
sim=RandomSimilarity(queryNorm=false): {}, locale=bg-BG, timezone=Asia/Kuching
[junit4] 2> NOTE: Linux 4.13.0-32-generic amd64/Oracle Corporation
1.8.0_162 (64-bit)/cpus=8,threads=1,free=114333000,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestIBSimilarityFactory,
TestIndexingPerformance, AnalyticsMergeStrategyTest, TestDynamicLoading,
TolerantUpdateProcessorTest, TestRTimerTree, TestQuerySenderNoQuery,
ComputePlanActionTest, TestComputePlanAction,
TestLegacyNumericRangeQueryBuilder, FullHLLTest, CustomHighlightComponentTest,
TestHighFrequencyDictionaryFactory, ZkFailoverTest,
TestDownShardTolerantSearch, TestJoin, RankQueryTest, TestCSVLoader,
SynonymTokenizerTest, TestHttpShardHandlerFactory, TestGraphMLResponseWriter,
FacetPivotSmallTest, TestDistributedSearch, CursorMarkTest,
CloneFieldUpdateProcessorFactoryTest, TestManagedSynonymGraphFilterFactory,
HdfsBasicDistributedZk2Test, SolrIndexSplitterTest,
TestDelegationWithHadoopAuth, DistribDocExpirationUpdateProcessorTest,
TestSolrCoreParser, RemoteQueryErrorTest, TestConfigSets,
SolrMetricsIntegrationTest, CoreSorterTest, SparseHLLTest, SimpleFacetsTest,
TestSolrQueryResponse, TestHashPartitioner, TestMergePolicyConfig,
TestSSLRandomization, TestLuceneIndexBackCompat, TestCryptoKeys,
TestSimpleQParserPlugin, PeerSyncWithIndexFingerprintCachingTest,
TestNumericTerms64, TestWriterPerf, JavabinLoaderTest,
TlogReplayBufferedWhileIndexingTest, TestSolrXml,
FieldAnalysisRequestHandlerTest, TestFieldTypeResource, StatsComponentTest,
HdfsDirectoryFactoryTest, CreateCollectionCleanupTest,
TestCollapseQParserPlugin, SubstringBytesRefFilterTest, TestFastOutputStream,
SolrCloudReportersTest, AutoAddReplicasIntegrationTest,
TestCopyFieldCollectionResource, TestHighlightDedupGrouping,
HdfsRecoveryZkTest, CursorPagingTest, TestLMDirichletSimilarityFactory,
TestSolrQueryParser, SpellCheckCollatorTest, TestEmbeddedSolrServerSchemaAPI,
V2StandaloneTest, TestNodeLostTrigger, TestGraphTermsQParserPlugin,
TestLegacyNumericUtils, OverseerModifyCollectionTest, SolrCLIZkUtilsTest,
TestWordDelimiterFilterFactory, UpdateParamsTest,
ParsingFieldUpdateProcessorsTest, TestPseudoReturnFields,
TestStressInPlaceUpdates, TestUniqueKeyFieldResource, DebugComponentTest,
TestStressUserVersions, TestRandomFaceting, HighlighterConfigTest,
TestBinaryField, TestDocTermOrds, TestReplicationHandlerBackup,
TestConfigReload, TestExtendedDismaxParser, HttpTriggerListenerTest,
ConcurrentCreateRoutedAliasTest, TestComponentsName,
TemplateUpdateProcessorTest, TestCloudSchemaless, TestRawResponseWriter,
DocValuesMissingTest, TestLuceneMatchVersion, BasicAuthStandaloneTest,
CollectionsAPIDistributedZkTest, IndexBasedSpellCheckerTest,
TestPhraseSuggestions, TestPHPSerializedResponseWriter, TestManagedSchema,
PreAnalyzedFieldManagedSchemaCloudTest, OpenCloseCoreStressTest,
TestGroupingSearch, ChaosMonkeySafeLeaderWithPullReplicasTest, UUIDFieldTest,
NumberUtilsTest, SolrCoreCheckLockOnStartupTest, SuggesterWFSTTest,
TestCloudJSONFacetJoinDomain, TestTriggerIntegration,
ClassificationUpdateProcessorFactoryTest, TestSolrCloudWithKerberosAlt,
TestGenericDistributedQueue, CdcrBootstrapTest, ActionThrottleTest,
TestFieldCacheWithThreads, TestAuthenticationFramework, BadIndexSchemaTest,
TestLeaderElectionZkExpiry, AddSchemaFieldsUpdateProcessorFactoryTest,
TestCollationField, DistributedTermsComponentTest,
LeaderInitiatedRecoveryOnShardRestartTest, TestRemoteStreaming,
BasicDistributedZkTest, TestRequestForwarding, TestSizeLimitedDistributedMap,
TestSkipOverseerOperations, TestStressLiveNodes, TestUtilizeNode,
ZkShardTermsTest, CollectionsAPIAsyncDistributedZkTest, TestCollectionAPI,
TestRequestStatusCollectionAPI, NodeLostTriggerTest, SystemLogListenerTest,
TriggerIntegrationTest]
[junit4] Completed [624/774 (1!)] on J0 in 210.41s, 14 tests, 2 failures <<<
FAILURES!
[...truncated 48661 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
