Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/414/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testCooldown
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([A7318FFE63EC6385:968FE21A1D461677]: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.testCooldown(TriggerIntegrationTest.java:1177)
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.client.solrj.io.stream.StreamExpressionTest.testDistributions
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([806090A8020763D1:3F9FD102DCFD834D]: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.client.solrj.io.stream.StreamExpressionTest.testDistributions(StreamExpressionTest.java:6639)
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 13625 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/init-core-data-001
[junit4] 2> 3839001 WARN
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=21 numCloses=21
[junit4] 2> 3839002 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3839003 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-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> 3839003 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3839003 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001
[junit4] 2> 3839003 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3839004 INFO (Thread-7093) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3839004 INFO (Thread-7093) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 3839007 ERROR (Thread-7093) [ ] 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> 3839104 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:51178
[junit4] 2> 3839116 INFO (zkConnectionManagerCallback-6053-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839130 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3839135 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3839138 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3839139 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@43c3e359{/solr,null,AVAILABLE}
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3f7f67eb{HTTP/1.1,[http/1.1]}{127.0.0.1:62061}
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ]
o.e.j.s.Server Started @3846075ms
[junit4] 2> 3839142 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62061}
[junit4] 2> 3839142 ERROR (jetty-launcher-6050-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@26e9c345{/solr,null,AVAILABLE}
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:39.074Z
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@780ce69e{HTTP/1.1,[http/1.1]}{127.0.0.1:45555}
[junit4] 2> 3839143 INFO (jetty-launcher-6050-thread-2) [ ]
o.e.j.s.Server Started @3846077ms
[junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45555}
[junit4] 2> 3839144 ERROR (jetty-launcher-6050-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3839144 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3839145 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:39.076Z
[junit4] 2> 3839147 INFO (zkConnectionManagerCallback-6055-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839148 INFO (zkConnectionManagerCallback-6057-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839149 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3839149 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3839178 INFO (jetty-launcher-6050-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3839178 INFO (jetty-launcher-6050-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3839179 INFO (zkConnectionManagerCallback-6063-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839180 INFO (zkConnectionManagerCallback-6065-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839182 INFO
(zkConnectionManagerCallback-6067-thread-1-processing-n:127.0.0.1:62061_solr)
[n:127.0.0.1:62061_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839183 INFO
(zkConnectionManagerCallback-6069-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839308 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3839308 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:45555_solr
[junit4] 2> 3839310 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.Overseer Overseer
(id=99417680703389702-127.0.0.1:45555_solr-n_0000000000) starting
[junit4] 2> 3839314 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3839316 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62061_solr
[junit4] 2> 3839319 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 3839322 INFO
(zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr)
[n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 3839325 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 3839326 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 3839326 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45555_solr
[junit4] 2> 3839326 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:62061_solr]
[junit4] 2> 3839326 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 3839326 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 3839327 INFO
(zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr)
[n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 3839328 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 3839329 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3839330 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 3839330 DEBUG (ScheduledTrigger-10134-thread-1)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 3839419 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45555.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839425 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45555.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839425 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45555.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839426 INFO (jetty-launcher-6050-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node2/.
[junit4] 2> 3839490 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62061.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839495 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62061.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839496 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62061.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3839496 INFO (jetty-launcher-6050-thread-1)
[n:127.0.0.1:62061_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node1/.
[junit4] 2> 3839515 INFO (zkConnectionManagerCallback-6075-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839519 INFO (zkConnectionManagerCallback-6079-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3839521 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3839522 INFO
(SUITE-TriggerIntegrationTest-seed#[A7318FFE63EC6385]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51178/solr ready
[junit4] 2> 3839685 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger
[junit4] 2> 3839686 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 3839686 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 3839686 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 3839692 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3839693 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3839693 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 3839696 INFO (qtp70527448-31078) [n:127.0.0.1:45555_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3839707 INFO (qtp70527448-31078) [n:127.0.0.1:45555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 3839710 INFO (qtp70527448-31084) [n:127.0.0.1:45555_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> 3839712 INFO (qtp2037307113-31070) [n:127.0.0.1:62061_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> 3839712 DEBUG (qtp70527448-31081) [n:127.0.0.1:45555_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3839713 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 3839714 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 3839717 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:62061_solr, 127.0.0.1:45555_solr]
[junit4] 2> 3839717 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 3839720 INFO
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 3839724 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3839724 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@3f7f67eb{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 3839727 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1982072115
[junit4] 2> 3839728 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 3839728 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9646f38: rootName =
solr_62061, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1e84c4f7
[junit4] 2> 3839728 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 3839728 DEBUG (ScheduledTrigger-10134-thread-1)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 2
[junit4] 2> 3839734 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 3839734 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@8c5a00e: rootName =
solr_62061, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@511c9d73
[junit4] 2> 3839738 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 3839738 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@47cc30f0: rootName =
solr_62061, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@4b02a68
[junit4] 2> 3839741 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:62061_solr
[junit4] 2> 3839742 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 3839743 INFO
(zkCallback-6066-thread-1-processing-n:127.0.0.1:62061_solr)
[n:127.0.0.1:62061_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 3839745 INFO (zkCallback-6078-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 3839745 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 3839746 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 3839747 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@43c3e359{/solr,null,UNAVAILABLE}
[junit4] 2> 3839750 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.e.j.s.session Stopped scavenging
[junit4] 2> 3840740 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 3840741 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node:
127.0.0.1:62061_solr
[junit4] 2> 3841705 INFO
(OverseerCollectionConfigSetProcessor-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 3841745 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger with currently live nodes: 1
[junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing
registered processor for lost nodes: [127.0.0.1:62061_solr]
[junit4] 2> 3842749 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":694984571875163,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694984571875163],
[junit4] 2> "nodeNames":["127.0.0.1:62061_solr"]}}
[junit4] 2> 3842754 DEBUG (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_lost_trigger]
[junit4] 2> 3842755 INFO (ScheduledTrigger-10134-thread-2)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 3842757 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss",
[junit4] 2> "source":"node_lost_trigger",
[junit4] 2> "eventTime":694984571875163,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694984571875163],
[junit4] 2> "_enqueue_time_":694986585374410,
[junit4] 2> "nodeNames":["127.0.0.1:62061_solr"]}}
[junit4] 2> 3842760 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_lost_trigger after 5000ms
[junit4] 2> 3842760 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 3
ms for event id=27815b487ef5bT1d5ts6h4h7mqkj92livzrfjss
[junit4] 2> 3842764 INFO (qtp70527448-31081) [n:127.0.0.1:45555_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> 3842764 DEBUG (qtp70527448-31083) [n:127.0.0.1:45555_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3842765 INFO (qtp70527448-31083) [n:127.0.0.1:45555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 3842766 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 3842767 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:45555_solr]
[junit4] 2> 3842767 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 3842768 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3842769 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 3844776 INFO
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[A7318FFE63EC6385]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
[junit4] 2> 3844928 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testListeners
[junit4] 2> 3844928 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 3844929 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3844929 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 3844929 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ba697f9{/solr,null,AVAILABLE}
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4bc41d2e{HTTP/1.1,[http/1.1]}{127.0.0.1:62904}
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server Started @3851866ms
[junit4] 2> 3844930 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62904}
[junit4] 2> 3844931 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3844931 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 3844931 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3844931 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3844931 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:44.862Z
[junit4] 2> 3844932 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3844933 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3844933 INFO (zkConnectionManagerCallback-6081-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3844933 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 3844934 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3844941 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3844943 INFO (zkConnectionManagerCallback-6085-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3844946 INFO
(zkConnectionManagerCallback-6087-thread-1-processing-n:127.0.0.1:62904_solr)
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3844958 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 3844961 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3844964 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62904_solr
[junit4] 2> 3844965 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 3844965 INFO (zkCallback-6078-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3844968 INFO
(zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr)
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 3845055 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62904.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845061 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62904.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845061 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62904.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845062 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:62904_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node3/.
[junit4] 2> 3845086 INFO (qtp70527448-31083) [n:127.0.0.1:45555_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> 3845087 INFO (qtp1183140683-31137) [n:127.0.0.1:62904_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> 3845087 DEBUG (qtp70527448-31084) [n:127.0.0.1:45555_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3845088 INFO (qtp70527448-31084) [n:127.0.0.1:45555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 3845089 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 3845089 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:62904_solr, 127.0.0.1:45555_solr]
[junit4] 2> 3845089 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 3845089 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 3845091 INFO
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 3845092 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3845092 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 3845093 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 3845093 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 3845095 INFO (qtp70527448-31084) [n:127.0.0.1:45555_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> 3845096 INFO (qtp1183140683-31140) [n:127.0.0.1:62904_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> 3845096 DEBUG (qtp1183140683-31142) [n:127.0.0.1:62904_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3845097 INFO (qtp1183140683-31142) [n:127.0.0.1:62904_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 3845097 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 3845098 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:62904_solr, 127.0.0.1:45555_solr]
[junit4] 2> 3845098 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 3845098 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 3845098 INFO (qtp70527448-31084) [n:127.0.0.1:45555_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> 3845098 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3845099 INFO (qtp1183140683-31139) [n:127.0.0.1:62904_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> 3845099 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 3845099 DEBUG (qtp70527448-31081) [n:127.0.0.1:45555_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3845099 INFO (qtp70527448-31081) [n:127.0.0.1:45555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 3845100 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 3845100 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3845100 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:62904_solr, 127.0.0.1:45555_solr]
[junit4] 2> 3845100 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction},
{name=test1,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}],
enabled=true}
[junit4] 2> 3845100 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 3845101 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3845101 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 3845101 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3845101 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3845101 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 3845101 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6bf8b020{/solr,null,AVAILABLE}
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@602e4338{HTTP/1.1,[http/1.1]}{127.0.0.1:34963}
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server Started @3852037ms
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34963}
[junit4] 2> 3845102 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3845102 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:45.033Z
[junit4] 2> 3845104 INFO (zkConnectionManagerCallback-6091-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3845104 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3845110 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3845111 INFO (zkConnectionManagerCallback-6095-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3845113 INFO
(zkConnectionManagerCallback-6097-thread-1-processing-n:127.0.0.1:34963_solr)
[n:127.0.0.1:34963_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3845123 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 3845125 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3845131 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34963_solr
[junit4] 2> 3845132 INFO
(zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr)
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 3845132 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 3845133 INFO (zkCallback-6078-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 3845136 INFO
(zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr)
[n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 3845197 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34963.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845204 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34963.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845204 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34963.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3845205 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:34963_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node4/.
[junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 3846095 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:34963_solr at time 694989927416308
[junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL firing registered processor for nodes:
[127.0.0.1:34963_solr] added at times [694989927416308], now=694990930852309
[junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":694989927416308,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694989927416308],
[junit4] 2> "nodeNames":["127.0.0.1:34963_solr"]}}
[junit4] 2> 3847098 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_triggerL]
[junit4] 2> 3847100 INFO (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 3847102 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":694989927416308,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694989927416308],
[junit4] 2> "_enqueue_time_":694990931313355,
[junit4] 2> "nodeNames":["127.0.0.1:34963_solr"]}}
[junit4] 2> 3847109 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_triggerL after 5000ms
[junit4] 2> 3847109 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 7
ms for event id=27816f3bf01f4T1d5ts6h4h7mqkj92livzrfjsu
[junit4] 2> 3849114 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3849116 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3849116 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3849116 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3849116 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@b7bec2c{/solr,null,AVAILABLE}
[junit4] 2> 3849117 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@77b415c7{HTTP/1.1,[http/1.1]}{127.0.0.1:47782}
[junit4] 2> 3849117 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.e.j.s.Server Started @3856054ms
[junit4] 2> 3849117 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47782}
[junit4] 2> 3849118 ERROR
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 3849118 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 3849118 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3849118 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3849119 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:49.049Z
[junit4] 2> 3849123 INFO (zkConnectionManagerCallback-6101-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3849124 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3849139 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3849143 INFO (zkConnectionManagerCallback-6105-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3849149 INFO
(zkConnectionManagerCallback-6107-thread-1-processing-n:127.0.0.1:47782_solr)
[n:127.0.0.1:47782_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3849174 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 3849179 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3849182 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47782_solr
[junit4] 2> 3849184 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 3849185 INFO
(zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr)
[n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 3849185 INFO (zkCallback-6078-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 3849185 INFO
(zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr)
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 3849188 INFO
(zkCallback-6106-thread-1-processing-n:127.0.0.1:47782_solr)
[n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 3849242 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47782.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3849248 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47782.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3849248 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_47782.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3849249 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385])
[n:127.0.0.1:47782_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node5/.
[junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 3852113 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:47782_solr at time 694995947762038
[junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerL
[junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerL firing registered processor for nodes:
[127.0.0.1:47782_solr] added at times [694995947762038], now=694996951622823
[junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":694995947762038,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694995947762038],
[junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}}
[junit4] 2> 3853117 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_triggerL]
[junit4] 2> 3853119 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":694995947762038,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694995947762038],
[junit4] 2> "_enqueue_time_":694996951971200,
[junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}}
[junit4] 2> 3853119 WARN
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Exception executing
actions
[junit4] 2> java.lang.Exception: Error executing action: test1 for trigger
event: {
[junit4] 2> "id":"278185a963176T1d5ts6h4h7mqkj92livzrfjsw",
[junit4] 2> "source":"node_added_triggerL",
[junit4] 2> "eventTime":694995947762038,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[694995947762038],
[junit4] 2> "_enqueue_time_":694996951971200,
[junit4] 2> "nodeNames":["127.0.0.1:47782_solr"]}}
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:303)
[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> Caused by: java.lang.RuntimeException: failure
[junit4] 2> at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction.process(TriggerIntegrationTest.java:989)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:300)
[junit4] 2> ... 6 more
[junit4] 2> 3853120 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_triggerL after 5000ms
[junit4] 2> 3853120 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=278185a963176T1d5ts6h4h7mqkj92livzrfjsw
[junit4] 2> 3855120 INFO
(TEST-TriggerIntegrationTest.testListeners-seed#[A7318FFE63EC6385]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testListeners
[junit4] 2> 3855423 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState
[junit4] 2> 3855424 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 9
[junit4] 2> 3855425 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 9
[junit4] 2> 3855425 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 9
[junit4] 2> 3855430 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3855431 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3855431 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 3855434 INFO (qtp1890717126-31166) [n:127.0.0.1:34963_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> 3855435 INFO (qtp70527448-31081) [n:127.0.0.1:45555_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> 3855437 INFO (qtp567234657-31186) [n:127.0.0.1:47782_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> 3855438 INFO (qtp1183140683-31139) [n:127.0.0.1:62904_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> 3855438 DEBUG (qtp1890717126-31162) [n:127.0.0.1:34963_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3855439 INFO (qtp1890717126-31162) [n:127.0.0.1:34963_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 3855439 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 10
[junit4] 2> 3855440 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3855440 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:62904_solr, 127.0.0.1:34963_solr, 127.0.0.1:47782_solr,
127.0.0.1:45555_solr]
[junit4] 2> 3855440 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_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> 3855440 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 10
[junit4] 2> 3855441 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3855441 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3855441 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 3855442 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7c282908{/solr,null,AVAILABLE}
[junit4] 2> 3855442 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@451dd20f{HTTP/1.1,[http/1.1]}{127.0.0.1:33215}
[junit4] 2> 3855442 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.Server Started @3862380ms
[junit4] 2> 3855442 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33215}
[junit4] 2> 3855442 ERROR
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 3855443 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 7.3.0
[junit4] 2> 3855443 INFO
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 3855443 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 3855443 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3855443 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-26T19:03:55.374Z
[junit4] 2> 3855443 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3855444 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 3855445 DEBUG (ScheduledTrigger-10134-thread-4)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 3855445 DEBUG (ScheduledTrigger-10134-thread-4)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 3855445 INFO (zkConnectionManagerCallback-6111-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3855446 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3855452 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51178/solr
[junit4] 2> 3855454 INFO (zkConnectionManagerCallback-6115-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3855457 INFO
(zkConnectionManagerCallback-6117-thread-1-processing-n:127.0.0.1:33215_solr)
[n:127.0.0.1:33215_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3855467 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 3855470 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3855473 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33215_solr
[junit4] 2> 3855475 INFO
(zkCallback-6106-thread-1-processing-n:127.0.0.1:47782_solr)
[n:127.0.0.1:47782_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 3855475 INFO
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 3855475 INFO
(zkCallback-6096-thread-1-processing-n:127.0.0.1:34963_solr)
[n:127.0.0.1:34963_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 3855475 INFO (zkCallback-6078-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 3855475 INFO
(zkCallback-6086-thread-1-processing-n:127.0.0.1:62904_solr)
[n:127.0.0.1:62904_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 3855478 INFO
(zkCallback-6116-thread-1-processing-n:127.0.0.1:33215_solr)
[n:127.0.0.1:33215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 3855550 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33215.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3855556 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33215.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3855556 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33215.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16bbc861
[junit4] 2> 3855557 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[n:127.0.0.1:33215_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_A7318FFE63EC6385-001/tempDir-001/node6/.
[junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5
[junit4] 2> 3856447 DEBUG (ScheduledTrigger-10134-thread-4)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:33215_solr at time 695000282715259
[junit4] 2> 3857102 INFO (qtp284080786-31212) [n:127.0.0.1:33215_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> 3857103 INFO (qtp1890717126-31166) [n:127.0.0.1:34963_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> 3857104 INFO (qtp70527448-31083) [n:127.0.0.1:45555_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> 3857106 INFO (qtp567234657-31188) [n:127.0.0.1:47782_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> 3857107 INFO (qtp1183140683-31142) [n:127.0.0.1:62904_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> 3857107 DEBUG (qtp1890717126-31164) [n:127.0.0.1:34963_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3857109 INFO (qtp1890717126-31164) [n:127.0.0.1:34963_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 3857110 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 11
[junit4] 2> 3857111 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:33215_solr, 127.0.0.1:62904_solr, 127.0.0.1:34963_solr,
127.0.0.1:47782_solr, 127.0.0.1:45555_solr]
[junit4] 2> 3857111 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_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> 3857111 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 11
[junit4] 2> 3857117 INFO
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 3857119 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Adding node from
marker path: 127.0.0.1:33215_solr
[junit4] 2> 3857121 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_restore_trigger
[junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 5
[junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_restore_trigger firing registered processor for nodes:
[127.0.0.1:33215_solr] added at times [695000954641981], now=695000957165488
[junit4] 2> 3857121 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx",
[junit4] 2> "source":"node_added_restore_trigger",
[junit4] 2> "eventTime":695000954641981,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[695000954641981],
[junit4] 2> "nodeNames":["127.0.0.1:33215_solr"]}}
[junit4] 2> 3857122 DEBUG (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_restore_trigger]
[junit4] 2> 3857122 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 3857123 INFO (ScheduledTrigger-10134-thread-3)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 3857123 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx",
[junit4] 2> "source":"node_added_restore_trigger",
[junit4] 2> "eventTime":695000954641981,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[695000954641981],
[junit4] 2> "_enqueue_time_":695000957639345,
[junit4] 2> "nodeNames":["127.0.0.1:33215_solr"]}}
[junit4] 2> 3857124 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[A7318FFE63EC6385])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTriggerRestoreState
[junit4] 2> 3857145 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_restore_trigger after 5000ms
[junit4] 2> 3857150 DEBUG
(AutoscalingActionExecutor-10135-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 27
ms for event id=2781985051e3dT1d5ts6h4h7mqkj92livzrfjsx
[junit4] 2> 3857495 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeMarkersRegistration
[junit4] 2> 3857496 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 12
[junit4] 2> 3857496 DEBUG
(zkCallback-6068-thread-1-processing-n:127.0.0.1:45555_solr)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 12
[junit4] 2> 3857496 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 12
[junit4] 2> 3857501 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 3857502 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 3857502 DEBUG
(OverseerAutoScalingTriggerThread-99417680703389702-127.0.0.1:45555_solr-n_0000000000)
[n:127.0.0.1:45555_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 12, lastZnodeVersion 12
[junit4] 2> 3857502 INFO (qtp284080786-31216) [n:127.0.0.1:33215_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3857511 INFO (qtp284080786-31216) [n:127.0.0.1:33215_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 3857512 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-22T05:27:37+08:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3857513 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3857513 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3857513 INFO
(TEST-TriggerIntegrationTest.testNodeMarkersRegistration-seed#[A7318FFE63EC6385])
[ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 3857513 INFO (TEST-TriggerIntegrationTest.testN
[...truncated too long message...]
nager Closing metric reporters for
registry=solr.core.collection1.shard2.replica_n2, tag=926609318
[junit4] 2> 256424 INFO (coreCloseExecutor-1525-thread-1)
[n:127.0.0.1:43530_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@cc7e1: rootName =
solr_43530, domain = solr.core.collection1.shard2.replica_n2, service url =
null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@75fbf211
[junit4] 2> 256431 INFO (jetty-closer-1093-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@245dbb7e{/solr,null,UNAVAILABLE}
[junit4] 2> 256433 INFO (jetty-closer-1093-thread-3) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 256440 INFO (coreCloseExecutor-1525-thread-1)
[n:127.0.0.1:43530_solr c:collection1 s:shard2 r:core_node4
x:collection1_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=926609318
[junit4] 2> 256444 INFO (jetty-closer-1093-thread-4) [ ]
o.a.s.c.Overseer Overseer
(id=99417744460742666-127.0.0.1:43530_solr-n_0000000000) closing
[junit4] 2> 256444 INFO
(OverseerStateUpdate-99417744460742666-127.0.0.1:43530_solr-n_0000000000)
[n:127.0.0.1:43530_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43530_solr
[junit4] 2> 256447 WARN
(OverseerAutoScalingTriggerThread-99417744460742666-127.0.0.1:43530_solr-n_0000000000)
[n:127.0.0.1:43530_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 256454 INFO (jetty-closer-1093-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7058845{/solr,null,UNAVAILABLE}
[junit4] 2> 256456 INFO (jetty-closer-1093-thread-4) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 257923 WARN
(zkCallback-1127-thread-2-processing-n:127.0.0.1:54144_solr)
[n:127.0.0.1:54144_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 257924 INFO (jetty-closer-1093-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@75340131{/solr,null,UNAVAILABLE}
[junit4] 2> 257925 INFO (jetty-closer-1093-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 257929 ERROR
(SUITE-StreamExpressionTest-seed#[806090A8020763D1]-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> 257930 INFO
(SUITE-StreamExpressionTest-seed#[806090A8020763D1]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:49045 49045
[junit4] 2> 263444 INFO (Thread-729) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:49045 49045
[junit4] 2> 263446 WARN (Thread-729) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/aliases.json
[junit4] 2> 13 /solr/configs/conf
[junit4] 2> 7 /solr/configs/ml
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/clusterprops.json
[junit4] 2> 100 /solr/clusterstate.json
[junit4] 2> 40
/solr/collections/destinationCollection/state.json
[junit4] 2> 26
/solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 20
/solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 12 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/mainCorpus1/state.json
[junit4] 2> 10 /solr/collections/destination/state.json
[junit4] 2> 10 /solr/collections/destination1/state.json
[junit4] 2> 10 /solr/collections/workQueue1/state.json
[junit4] 2> 10 /solr/collections/workQueue/state.json
[junit4] 2> 10 /solr/collections/uknownCollection/state.json
[junit4] 2> 10 /solr/collections/modelCollection/state.json
[junit4] 2> 10
/solr/collections/checkpointCollection/state.json
[junit4] 2> 10 /solr/collections/mainCorpus/state.json
[junit4] 2> 2
/solr/overseer_elect/election/99417744460742666-127.0.0.1:43530_solr-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/99417744460742668-127.0.0.1:54144_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 100 /solr/live_nodes
[junit4] 2> 100 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.StreamExpressionTest_806090A8020763D1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{expr_s=PostingsFormat(name=Memory), name_s=PostingsFormat(name=MockRandom),
terms_ss=FSTOrd50, join2_s=FSTOrd50,
field_s=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=FSTOrd50,
subject=FSTOrd50, a1_s=PostingsFormat(name=LuceneVarGapFixedInterval),
a2_s=PostingsFormat(name=Memory), level1_s=PostingsFormat(name=Memory),
body_t=PostingsFormat(name=MockRandom), s_multi=PostingsFormat(name=Memory),
col_s=PostingsFormat(name=LuceneVarGapFixedInterval),
level2_s=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=FSTOrd50,
a_s=PostingsFormat(name=Memory),
tv_text=PostingsFormat(name=LuceneVarGapFixedInterval), term_s=FSTOrd50,
a_t=FSTOrd50, text_s=PostingsFormat(name=Memory),
id=PostingsFormat(name=MockRandom), text=PostingsFormat(name=Memory),
featureSet_s=PostingsFormat(name=LuceneVarGapFixedInterval),
checkpoint_ss=PostingsFormat(name=MockRandom),
test_t=PostingsFormat(name=MockRandom),
ident_s=PostingsFormat(name=LuceneVarGapFixedInterval),
whitetok=PostingsFormat(name=MockRandom),
side_s=PostingsFormat(name=MockRandom)},
docValues:{expr_s=DocValuesFormat(name=Direct),
name_s=DocValuesFormat(name=Lucene70),
iteration_i=DocValuesFormat(name=Lucene70),
terms_ss=DocValuesFormat(name=Asserting),
join2_s=DocValuesFormat(name=Asserting),
multiDefault=DocValuesFormat(name=Asserting),
intDefault=DocValuesFormat(name=Memory), a_f=DocValuesFormat(name=Memory),
a2_s=DocValuesFormat(name=Direct),
falseNegative_i=DocValuesFormat(name=Memory),
a_i=DocValuesFormat(name=Lucene70), level1_s=DocValuesFormat(name=Direct),
a_s=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene70),
alpha_d=DocValuesFormat(name=Lucene70), i_multi=DocValuesFormat(name=Lucene70),
idf_d=DocValuesFormat(name=Memory), b_ls=DocValuesFormat(name=Memory),
checkpoint_ss=DocValuesFormat(name=Lucene70),
ident_s=DocValuesFormat(name=Memory), order_i=DocValuesFormat(name=Asserting),
error_d=DocValuesFormat(name=Direct), side_s=DocValuesFormat(name=Lucene70),
truePositive_i=DocValuesFormat(name=Direct),
miles_i=DocValuesFormat(name=Memory), field_s=DocValuesFormat(name=Memory),
price_f=DocValuesFormat(name=Asserting), a1_s=DocValuesFormat(name=Memory),
join1_i=DocValuesFormat(name=Direct), join3_i=DocValuesFormat(name=Lucene70),
test_i=DocValuesFormat(name=Asserting),
falsePositive_i=DocValuesFormat(name=Memory),
field_i=DocValuesFormat(name=Asserting), s_multi=DocValuesFormat(name=Direct),
col_s=DocValuesFormat(name=Memory), level2_s=DocValuesFormat(name=Memory),
a_ss=DocValuesFormat(name=Asserting), score_f=DocValuesFormat(name=Direct),
term_s=DocValuesFormat(name=Asserting), text_s=DocValuesFormat(name=Direct),
timestamp=DocValuesFormat(name=Asserting),
idfs_ds=DocValuesFormat(name=Asserting),
weights_ds=DocValuesFormat(name=Lucene70),
featureSet_s=DocValuesFormat(name=Memory),
trueNegative_i=DocValuesFormat(name=Direct),
index_i=DocValuesFormat(name=Memory), test_dt=DocValuesFormat(name=Direct),
out_i=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Memory)},
maxPointsInLeafNode=1459, maxMBSortInHeap=6.369987389265701,
sim=RandomSimilarity(queryNorm=false): {}, locale=id-ID, timezone=Europe/Athens
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152
(64-bit)/cpus=3,threads=1,free=76827208,total=179453952
[junit4] 2> NOTE: All tests run in this JVM: [TestToleratedUpdateError,
ArrayEvaluatorTest, CloudSolrClientBuilderTest,
StreamExpressionToExpessionTest, FieldAnalysisResponseTest, NotEvaluatorTest,
RecursiveEvaluatorTest, UuidEvaluatorTest, TestDocumentObjectBinder,
JettyWebappTest, UsingSolrJRefGuideExamplesTest, HttpSolrClientBuilderTest,
ShardParamsTest, GreaterThanEvaluatorTest, LengthEvaluatorTest, TestRetryUtil,
ExclusiveOrEvaluatorTest, TestHash, SolrExampleEmbeddedTest,
TestCloudSolrClientConnections, NormalDistributionEvaluatorTest,
TestSolrProperties, SchemaTest, TestXMLEscaping, SineEvaluatorTest,
SubtractEvaluatorTest, AddEvaluatorTest, TestDelegationTokenRequest,
MultiplyEvaluatorTest, TestDelegationTokenResponse, AppendEvaluatorTest,
TestUpdateRequestCodec, TestSpellCheckResponse, SelectWithEvaluatorsTest,
TestEmbeddedSolrServer, SolrExampleStreamingTest, SolrExampleXMLTest,
SolrExampleJettyTest, TestBatchUpdate, LargeVolumeJettyTest, SolrParamTest,
TermsResponseTest, MergeIndexesEmbeddedTest, QueryResponseTest, TestCoreAdmin,
TestJavaBinCodec, SolrQueryTest, ModifiableSolrParamsTest, TestFastInputStream,
DocumentAnalysisResponseTest, SolrDocumentTest, SolrExceptionTest,
ClientUtilsTest, NamedListTest, AnlysisResponseBaseTest, GetByIdTest,
TestLBHttpSolrClient, TestPolicy, BasicHttpSolrClientTest,
CloudSolrClientMultiConstructorTest, ConcurrentUpdateSolrClientBuilderTest,
HttpSolrClientConPoolTest, HttpSolrClientSSLAuthConPoolTest,
LBHttpSolrClientBuilderTest, GraphTest, JdbcTest, StreamExpressionTest]
[junit4] Completed [146/146 (1!)] on J0 in 119.49s, 153 tests, 1 failure <<<
FAILURES!
[...truncated 47275 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
