Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1243/
Java: 64bit/jdk-10-ea+37 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED:
org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testDistributions
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([151C81FB2F33C268:AAE3C051F1C922F4]: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:6637)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED:
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([F56B0DD2318758FA:4F673A5D6E6F8EB5]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.junit.Assert.assertNull(Assert.java:562)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testMetricTrigger(TriggerIntegrationTest.java:1575)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12642 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/init-core-data-001
[junit4] 2> 771915 WARN
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 771915 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 771916 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 771916 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 771916 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/tempDir-001
[junit4] 2> 771916 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 771916 INFO (Thread-1322) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 771917 INFO (Thread-1322) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 771918 ERROR (Thread-1322) [ ] 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> 772017 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:46527
[junit4] 2> 772018 INFO (zkConnectionManagerCallback-1578-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772020 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 772020 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@969916a{/solr,null,AVAILABLE}
[junit4] 2> 772021 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2c3c0ea7{/solr,null,AVAILABLE}
[junit4] 2> 772022 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@524d294{HTTP/1.1,[http/1.1]}{127.0.0.1:41849}
[junit4] 2> 772022 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2c8a75cb{HTTP/1.1,[http/1.1]}{127.0.0.1:37777}
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.e.j.s.Server Started @774139ms
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.e.j.s.Server Started @774139ms
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41849}
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37777}
[junit4] 2> 772023 ERROR (jetty-launcher-1575-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 772023 ERROR (jetty-launcher-1575-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-25T22:54:10.822398Z
[junit4] 2> 772023 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-25T22:54:10.822416Z
[junit4] 2> 772024 INFO (zkConnectionManagerCallback-1582-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772024 INFO (zkConnectionManagerCallback-1581-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772024 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 772024 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 772028 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1612f87491c0001, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 772029 INFO (jetty-launcher-1575-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46527/solr
[junit4] 2> 772029 INFO (jetty-launcher-1575-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46527/solr
[junit4] 2> 772030 INFO (zkConnectionManagerCallback-1588-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772030 INFO (zkConnectionManagerCallback-1590-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772031 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x1612f87491c0004, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 772031 INFO
(zkConnectionManagerCallback-1592-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772031 INFO
(zkConnectionManagerCallback-1594-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772101 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 772101 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 772102 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:37777_solr
[junit4] 2> 772102 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41849_solr
[junit4] 2> 772102 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.Overseer Overseer
(id=99412924872523781-127.0.0.1:37777_solr-n_0000000000) starting
[junit4] 2> 772108 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 772109 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 772109 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 772110 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 772110 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37777_solr
[junit4] 2> 772110 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:41849_solr]
[junit4] 2> 772110 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 772110 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 772110 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 772111 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 772112 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 772112 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 772112 DEBUG (ScheduledTrigger-3366-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 772151 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37777.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772153 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41849.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772157 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37777.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772157 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37777.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772158 INFO (jetty-launcher-1575-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/tempDir-001/node1/.
[junit4] 2> 772161 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41849.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772161 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41849.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772162 INFO (jetty-launcher-1575-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/tempDir-001/node2/.
[junit4] 2> 772246 INFO (zkConnectionManagerCallback-1600-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772248 INFO (zkConnectionManagerCallback-1604-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772249 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 772250 INFO
(SUITE-TriggerIntegrationTest-seed#[F56B0DD2318758FA]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46527/solr ready
[junit4] 2> 772255 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger
[junit4] 2> 772256 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 772256 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 772256 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 772257 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 772257 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 772257 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 772261 INFO (qtp650930320-7114) [n:127.0.0.1:37777_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> 772262 INFO (qtp1212979569-7054) [n:127.0.0.1:41849_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> 772262 DEBUG (qtp650930320-7055) [n:127.0.0.1:37777_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 772262 INFO (qtp650930320-7055) [n:127.0.0.1:37777_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 772262 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 772264 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:37777_solr, 127.0.0.1:41849_solr]
[junit4] 2> 772264 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=2,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 772264 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 772265 INFO
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 772265 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 772265 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 772266 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 772266 DEBUG (ScheduledTrigger-3366-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 772266 DEBUG (ScheduledTrigger-3366-thread-1)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 772266 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 772266 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 772266 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6792ae36{/solr,null,AVAILABLE}
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@735959e8{HTTP/1.1,[http/1.1]}{127.0.0.1:43151}
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.e.j.s.Server Started @774383ms
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43151}
[junit4] 2> 772267 ERROR
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 772267 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-25T22:54:11.066944Z
[junit4] 2> 772269 INFO (zkConnectionManagerCallback-1606-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772269 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 772274 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46527/solr
[junit4] 2> 772275 INFO (zkConnectionManagerCallback-1610-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772276 INFO
(zkConnectionManagerCallback-1612-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 772279 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 772280 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 772281 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43151_solr
[junit4] 2> 772282 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 772282 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 772282 INFO (zkCallback-1603-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 772282 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 772312 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43151.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772320 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43151.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772320 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43151.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 772321 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43151_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/tempDir-001/node3/.
[junit4] 2> 773267 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 773267 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 773267 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:43151_solr at time 214636395300238
[junit4] 2> 774268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 774268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 775268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger
[junit4] 2> 775268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 775268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger firing registered processor for nodes:
[127.0.0.1:43151_solr] added at times [214636395300238], now=214638396279801
[junit4] 2> 775268 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"c335ee19058eT7p4mltw2n7sx3cykiqu6106qj",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":214636395300238,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214636395300238],
[junit4] 2> "nodeNames":["127.0.0.1:43151_solr"]}}
[junit4] 2> 775269 DEBUG (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_trigger]
[junit4] 2> 775269 INFO (ScheduledTrigger-3366-thread-2)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 775269 DEBUG
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"c335ee19058eT7p4mltw2n7sx3cykiqu6106qj",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":214636395300238,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214636395300238],
[junit4] 2> "_enqueue_time_":214638396738650,
[junit4] 2> "nodeNames":["127.0.0.1:43151_solr"]}}
[junit4] 2> 775270 DEBUG
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger after 5000ms
[junit4] 2> 775270 DEBUG
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 0
ms for event id=c335ee19058eT7p4mltw2n7sx3cykiqu6106qj
[junit4] 2> 775274 INFO (qtp650930320-7067) [n:127.0.0.1:37777_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> 775276 INFO (qtp1212979569-7062) [n:127.0.0.1:41849_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> 775277 INFO (qtp369078986-7120) [n:127.0.0.1:43151_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> 775277 DEBUG (qtp650930320-7063) [n:127.0.0.1:37777_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 775277 INFO (qtp650930320-7063) [n:127.0.0.1:37777_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 775277 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 775278 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:37777_solr, 127.0.0.1:43151_solr, 127.0.0.1:41849_solr]
[junit4] 2> 775278 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=2,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 775278 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 775278 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 775278 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 777278 INFO
(TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[F56B0DD2318758FA]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTrigger
[junit4] 2> 777287 INFO
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSetProperties
[junit4] 2> 777287 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 777288 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 777288 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 777288 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 777288 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 777288 INFO
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 5
[junit4] 2> 778301 DEBUG
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property:
triggerScheduleDelaySeconds from: 1 to: 4
[junit4] 2> 782318 DEBUG
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property:
triggerScheduleDelaySeconds from: 4 to: 1
[junit4] 2> 792322 DEBUG
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property:
triggerCorePoolSize from: 4 to: 6
[junit4] 2> 802331 INFO
(TEST-TriggerIntegrationTest.testSetProperties-seed#[F56B0DD2318758FA]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testSetProperties
[junit4] 2> 802347 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEventQueue
[junit4] 2> 802348 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 6
[junit4] 2> 802348 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 802348 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 802348 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 802349 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 802349 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 802351 INFO (qtp650930320-7113) [n:127.0.0.1:37777_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 802356 INFO (qtp650930320-7113) [n:127.0.0.1:37777_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 802360 INFO (qtp650930320-7065) [n:127.0.0.1:37777_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> 802362 INFO (qtp1212979569-7066) [n:127.0.0.1:41849_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> 802364 INFO (qtp369078986-7124) [n:127.0.0.1:43151_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> 802364 DEBUG (qtp650930320-7063) [n:127.0.0.1:37777_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 802365 INFO (qtp650930320-7063) [n:127.0.0.1:37777_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 802365 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 802366 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.TriggerIntegrationTest
TestEventQueueAction instantiated
[junit4] 2> 802366 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:37777_solr, 127.0.0.1:43151_solr, 127.0.0.1:41849_solr]
[junit4] 2> 802366 DEBUG
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerEQ instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}],
enabled=true}
[junit4] 2> 802366 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 7
[junit4] 2> 802368 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 802368 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 802368 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 802368 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerEQ
[junit4] 2> 802368 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 802369 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 802370 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 802370 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 802370 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 802370 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@63b5161e{/solr,null,AVAILABLE}
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2db303c8{HTTP/1.1,[http/1.1]}{127.0.0.1:43633}
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.Server Started @804487ms
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43633}
[junit4] 2> 802371 ERROR
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 802371 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-25T22:54:41.170587Z
[junit4] 2> 802373 INFO (zkConnectionManagerCallback-1616-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 802373 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 802380 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46527/solr
[junit4] 2> 802381 INFO (zkConnectionManagerCallback-1620-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 802383 INFO
(zkConnectionManagerCallback-1622-thread-1-processing-n:127.0.0.1:43633_solr)
[n:127.0.0.1:43633_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 802386 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 802386 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 802387 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43633_solr
[junit4] 2> 802388 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 802388 INFO (zkCallback-1603-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 802388 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 802388 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 802389 INFO
(zkCallback-1621-thread-1-processing-n:127.0.0.1:43633_solr)
[n:127.0.0.1:43633_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 802415 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43633.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 802420 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43633.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 802421 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43633.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@342b30c5
[junit4] 2> 802421 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA])
[n:127.0.0.1:43633_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F56B0DD2318758FA-001/tempDir-001/node4/.
[junit4] 2> 803369 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerEQ
[junit4] 2> 803369 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 803369 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:43633_solr at time 214666497208719
[junit4] 2> 804354 INFO
(OverseerCollectionConfigSetProcessor-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_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> 804370 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerEQ
[junit4] 2> 804371 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
[junit4] 2> 804371 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerEQ firing registered processor for nodes:
[127.0.0.1:43633_solr] added at times [214666497208719], now=214667498730467
[junit4] 2> 804371 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"]}}
[junit4] 2> 804371 DEBUG (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[node_added_triggerEQ]
[junit4] 2> 804371 INFO (ScheduledTrigger-3366-thread-3)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 804371 DEBUG
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "_enqueue_time_":214667498860834,
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"]}}
[junit4] 2> 804371 INFO
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.TriggerIntegrationTest -- event: {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "_enqueue_time_":214667498860834,
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"]}}
[junit4] 2> 804372 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2c8a75cb{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 804373 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1592650796
[junit4] 2> 804373 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 804373 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@363fdc81: rootName =
solr_37777, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@6310f4dd
[junit4] 2> 804377 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 804377 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4a75a00: rootName =
solr_37777, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@2fbd3000
[junit4] 2> 804380 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 804380 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@566141c7: rootName =
solr_37777, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@4c738714
[junit4] 2> 804381 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37777_solr
[junit4] 2> 804381 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 804381 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 804381 INFO
(zkCallback-1591-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 804381 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 804381 INFO (zkCallback-1603-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 804381 INFO
(zkCallback-1621-thread-1-processing-n:127.0.0.1:43633_solr)
[n:127.0.0.1:43633_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 804381 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 804381 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.Overseer Overseer
(id=99412924872523781-127.0.0.1:37777_solr-n_0000000000) closing
[junit4] 2> 804381 INFO
(OverseerStateUpdate-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:37777_solr
[junit4] 2> 804381 WARN
(OverseerAutoScalingTriggerThread-99412924872523781-127.0.0.1:37777_solr-n_0000000000)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 804382 DEBUG
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 804382 WARN
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers Exception executing
actions
[junit4] 2> org.apache.lucene.store.AlreadyClosedException:
ScheduledTrigger node_added_triggerEQ has been closed.
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers$ScheduledTrigger.dequeue(ScheduledTriggers.java:526)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$add$3(ScheduledTriggers.java:310)
[junit4] 2> at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
[junit4] 2> at
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] 2> at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 804382 DEBUG
(AutoscalingActionExecutor-3367-thread-1-processing-n:127.0.0.1:37777_solr)
[n:127.0.0.1:37777_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 10
ms for event id=c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql
[junit4] 2> 804382 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2c3c0ea7{/solr,null,UNAVAILABLE}
[junit4] 2> 804382 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:41849_solr
[junit4] 2> 804382 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 804383 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.Overseer Overseer
(id=99412924872523782-127.0.0.1:41849_solr-n_0000000001) starting
[junit4] 2> 804385 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 804385 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 8
[junit4] 2> 804385 INFO
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.TriggerIntegrationTest
TestEventQueueAction instantiated
[junit4] 2> 804385 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:43151_solr, 127.0.0.1:41849_solr, 127.0.0.1:43633_solr]
[junit4] 2> 804385 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerEQ instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}],
enabled=true}
[junit4] 2> 804386 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:43151_solr, 127.0.0.1:41849_solr, 127.0.0.1:43633_solr]
[junit4] 2> 804386 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion -1
[junit4] 2> 804386 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 8
[junit4] 2> 804386 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 804386 DEBUG (ScheduledTrigger-3389-thread-1)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 804386 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 804387 DEBUG (ScheduledTrigger-3389-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "_dequeue_time_":214667514825038,
[junit4] 2> "_enqueue_time_":214667498860834,
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 804387 DEBUG (ScheduledTrigger-3389-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_triggerEQ]
[junit4] 2> 804387 INFO (ScheduledTrigger-3389-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 804387 DEBUG
(AutoscalingActionExecutor-3390-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "_dequeue_time_":214667514825038,
[junit4] 2> "_enqueue_time_":214667498860834,
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 804387 INFO
(AutoscalingActionExecutor-3390-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.TriggerIntegrationTest -- event: {
[junit4] 2> "id":"c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql",
[junit4] 2> "source":"node_added_triggerEQ",
[junit4] 2> "eventTime":214666497208719,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[214666497208719],
[junit4] 2> "_dequeue_time_":214667514825038,
[junit4] 2> "_enqueue_time_":214667498860834,
[junit4] 2> "nodeNames":["127.0.0.1:43633_solr"],
[junit4] 2> "replaying":true}}
[junit4] 2> 804388 DEBUG (ScheduledTrigger-3389-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerEQ
[junit4] 2> 804388 DEBUG (ScheduledTrigger-3389-thread-2)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 804388 DEBUG
(AutoscalingActionExecutor-3390-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 804388 DEBUG
(AutoscalingActionExecutor-3390-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_triggerEQ after 5000ms
[junit4] 2> 804388 DEBUG
(AutoscalingActionExecutor-3390-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=c33cf04fb18fT7p4mltw2n7sx3cykiqu6106ql
[junit4] 2> 809383 INFO
(TEST-TriggerIntegrationTest.testEventQueue-seed#[F56B0DD2318758FA]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEventQueue
[junit4] 2> 809389 DEBUG (ScheduledTrigger-3389-thread-1)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 809389 DEBUG (ScheduledTrigger-3389-thread-3)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerEQ
[junit4] 2> 809389 DEBUG (ScheduledTrigger-3389-thread-3)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 809394 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testContinueTriggersOnOverseerRestart
[junit4] 2> 809394 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode
version 9
[junit4] 2> 809394 DEBUG
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 9
[junit4] 2> 809395 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 9
[junit4] 2> 809396 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 809396 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 809396 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 9, lastZnodeVersion 9
[junit4] 2> 809397 INFO (qtp1212979569-7066) [n:127.0.0.1:41849_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 809402 INFO (qtp1212979569-7066) [n:127.0.0.1:41849_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 809405 INFO (qtp1212979569-7054) [n:127.0.0.1:41849_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> 809406 INFO (qtp369078986-7124) [n:127.0.0.1:43151_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> 809417 INFO (qtp1835792801-7155) [n:127.0.0.1:43633_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> 809417 DEBUG (qtp1212979569-7064) [n:127.0.0.1:41849_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 809418 INFO (qtp1212979569-7064) [n:127.0.0.1:41849_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=14
[junit4] 2> 809418 DEBUG
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 10
[junit4] 2> 809418 DEBUG
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:43151_solr, 127.0.0.1:41849_solr, 127.0.0.1:43633_solr]
[junit4] 2> 809418 DEBUG
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerCTOOR instantiated with properties: {event=nodeAdded,
waitFor=2, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 809418 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 10
[junit4] 2> 809419 INFO
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 809420 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 809420 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 10, lastZnodeVersion 10
[junit4] 2> 809420 DEBUG (ScheduledTrigger-3389-thread-4)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 809420 DEBUG (ScheduledTrigger-3389-thread-4)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 809421 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.AbstractConnector Stopped
ServerConnector@524d294{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 809428 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1704907949
[junit4] 2> 809428 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.node, tag=null
[junit4] 2> 809428 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@481a50e5: rootName =
solr_41849, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@2510e64c
[junit4] 2> 809435 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jvm, tag=null
[junit4] 2> 809435 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@68bcd663: rootName =
solr_41849, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@2fbd3000
[junit4] 2> 809442 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 809442 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@42699856: rootName =
solr_41849, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@4c738714
[junit4] 2> 809443 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:41849_solr
[junit4] 2> 809444 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 809444 INFO (zkCallback-1603-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 809444 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 809444 INFO
(zkCallback-1621-thread-1-processing-n:127.0.0.1:43633_solr)
[n:127.0.0.1:43633_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 809444 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 809444 INFO
(zkCallback-1593-thread-1-processing-n:127.0.0.1:41849_solr)
[n:127.0.0.1:41849_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 809444 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.Overseer Overseer
(id=99412924872523782-127.0.0.1:41849_solr-n_0000000001) closing
[junit4] 2> 809445 INFO
(OverseerStateUpdate-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:41849_solr
[junit4] 2> 809445 WARN
(OverseerAutoScalingTriggerThread-99412924872523782-127.0.0.1:41849_solr-n_0000000001)
[n:127.0.0.1:41849_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 809448 DEBUG
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 809450 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:43151_solr
[junit4] 2> 809450 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@969916a{/solr,null,UNAVAILABLE}
[junit4] 2> 809450 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 809451 INFO
(zkCallback-1611-thread-1-processing-n:127.0.0.1:43151_solr)
[n:127.0.0.1:43151_solr ] o.a.s.c.Overseer Overseer
(id=99412924872523787-127.0.0.1:43151_solr-n_0000000002) starting
[junit4] 2> 809456 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 809457 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 11
[junit4] 2> 809457 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:43151_solr, 127.0.0.1:43633_solr]
[junit4] 2> 809457 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_triggerCTOOR instantiated with properties: {event=nodeAdded,
waitFor=2, actions=[{name=test,
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
enabled=true}
[junit4] 2> 809458 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:43151_solr, 127.0.0.1:43633_solr]
[junit4] 2> 809458 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 11, lastZnodeVersion -1
[junit4] 2> 809458 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 11
[junit4] 2> 809458 INFO
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 809459 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 809460 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 809460 DEBUG
(OverseerAutoScalingTriggerThread-99412924872523787-127.0.0.1:43151_solr-n_0000000002)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 11, lastZnodeVersion 11
[junit4] 2> 809460 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 810460 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 810460 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 810461 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 811464 DEBUG (ScheduledTrigger-3394-thread-4)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 811464 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 811464 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 812464 DEBUG (ScheduledTrigger-3394-thread-4)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 812464 DEBUG (ScheduledTrigger-3394-thread-4)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 812465 DEBUG (ScheduledTrigger-3394-thread-4)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 813465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 813465 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 813465 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 814465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 814465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 814465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 815465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 815465 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 815465 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 816465 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 816465 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 816465 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 817466 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 817466 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 817466 DEBUG (ScheduledTrigger-3394-thread-1)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 818466 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_triggerCTOOR
[junit4] 2> 818466 DEBUG (ScheduledTrigger-3394-thread-3)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 818466 DEBUG (ScheduledTrigger-3394-thread-2)
[n:127.0.0.1:43151_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 819456 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 819456 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 819456 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 819456 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3874d555{/solr,null,AVAILABLE}
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.AbstractConnector Started
ServerConnector@3fc047c1{HTTP/1.1,[http/1.1]}{127.0.0.1:39065}
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.e.j.s.Server Started @821573ms
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39065}
[junit4] 2> 819457 ERROR
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 7.3.0
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 819457 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-25T22:54:58.256838Z
[junit4] 2> 819458 INFO (zkConnectionManagerCallback-1626-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 819459 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 819463 INFO
(TEST-TriggerIntegrationTest.testContinueTriggersOnOverseerRestart-seed#[F56B0DD2318758FA])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46527/solr
[junit4] 2> 819464 INFO (zkConnectionManagerCallback-1630-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 819465 INFO (zkConnection
[...truncated too long message...]
ection s:shard1 r:core_node3 x:collection1_collection_shard1_replica_n1]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (0)
[junit4] 2> 161970 INFO (coreCloseExecutor-802-thread-1)
[n:127.0.0.1:44163_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.c.SolrCore
[collection1_collection_shard2_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1f330e3a
[junit4] 2> 161970 INFO (coreCloseExecutor-802-thread-1)
[n:127.0.0.1:44163_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.collection1_collection.shard2.replica_n2, tag=523439674
[junit4] 2> 161970 INFO (coreCloseExecutor-800-thread-1)
[n:127.0.0.1:37207_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_collection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@448e5d25
[junit4] 2> 161970 INFO (coreCloseExecutor-800-thread-1)
[n:127.0.0.1:37207_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.collection1_collection.shard1.replica_n1, tag=1150180645
[junit4] 2> 161970 INFO (coreCloseExecutor-802-thread-1)
[n:127.0.0.1:44163_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7822c141: rootName
= solr_44163, domain = solr.core.collection1_collection.shard2.replica_n2,
service url = null, agent id = null] for registry
solr.core.collection1_collection.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@781f6df8
[junit4] 2> 161970 INFO (coreCloseExecutor-800-thread-1)
[n:127.0.0.1:37207_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c62f5df: rootName
= solr_37207, domain = solr.core.collection1_collection.shard1.replica_n1,
service url = null, agent id = null] for registry
solr.core.collection1_collection.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@1d07c988
[junit4] 2> 161971 INFO (jetty-closer-240-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@13417635{/solr,null,UNAVAILABLE}
[junit4] 2> 161978 INFO (coreCloseExecutor-800-thread-1)
[n:127.0.0.1:37207_solr c:collection1_collection s:shard1 r:core_node3
x:collection1_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.collection1_collection.shard1.leader, tag=1150180645
[junit4] 2> 162004 INFO (jetty-closer-240-thread-3) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 161976 INFO (coreCloseExecutor-802-thread-1)
[n:127.0.0.1:44163_solr c:collection1_collection s:shard2 r:core_node4
x:collection1_collection_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.collection1_collection.shard2.leader, tag=523439674
[junit4] 2> 161971 INFO (jetty-closer-240-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3d2db77a{/solr,null,UNAVAILABLE}
[junit4] 2> 162004 INFO (jetty-closer-240-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 162006 INFO (jetty-closer-240-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=99413062113361930-127.0.0.1:44163_solr-n_0000000000) closing
[junit4] 2> 162006 INFO
(OverseerStateUpdate-99413062113361930-127.0.0.1:44163_solr-n_0000000000)
[n:127.0.0.1:44163_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:44163_solr
[junit4] 2> 162006 WARN
(OverseerAutoScalingTriggerThread-99413062113361930-127.0.0.1:44163_solr-n_0000000000)
[n:127.0.0.1:44163_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 162007 INFO (jetty-closer-240-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6af3db96{/solr,null,UNAVAILABLE}
[junit4] 2> 162007 INFO (jetty-closer-240-thread-4) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 162007 INFO (jetty-closer-240-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4f1bd50{/solr,null,UNAVAILABLE}
[junit4] 2> 162008 INFO (jetty-closer-240-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 162009 ERROR
(SUITE-StreamExpressionTest-seed#[151C81FB2F33C268]-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> 162009 INFO
(SUITE-StreamExpressionTest-seed#[151C81FB2F33C268]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45639 45639
[junit4] 2> 162818 INFO (Thread-272) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:45639 45639
[junit4] 2> 162819 WARN (Thread-272) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 80 /solr/aliases.json
[junit4] 2> 80 /solr/clusterprops.json
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 80 /solr/clusterstate.json
[junit4] 2> 20
/solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 20
/solr/collections/destinationCollection/state.json
[junit4] 2> 18
/solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 12
/solr/collections/collection1_collection/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/mainCorpus/state.json
[junit4] 2> 2
/solr/overseer_elect/election/99413062113361930-127.0.0.1:44163_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 80 /solr/live_nodes
[junit4] 2> 80 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_151C81FB2F33C268-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{expr_s=PostingsFormat(name=LuceneFixedGap),
join2_s=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Direct),
subject=PostingsFormat(name=Direct), a1_s=PostingsFormat(name=Direct),
a2_s=FSTOrd50, test_t=FSTOrd50, level1_s=PostingsFormat(name=LuceneFixedGap),
body_t=FSTOrd50, ident_s=PostingsFormat(name=Direct), s_multi=FSTOrd50,
a_ss=Lucene50(blocksize=128), col_s=PostingsFormat(name=Direct),
level2_s=Lucene50(blocksize=128), a_s=FSTOrd50, a_t=Lucene50(blocksize=128),
side_s=FSTOrd50, id=PostingsFormat(name=LuceneFixedGap), text=FSTOrd50},
docValues:{expr_s=DocValuesFormat(name=Direct),
join2_s=DocValuesFormat(name=Lucene70),
multiDefault=DocValuesFormat(name=Lucene70),
price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70),
intDefault=DocValuesFormat(name=Lucene70), a_f=DocValuesFormat(name=Lucene70),
join1_i=DocValuesFormat(name=Direct), a2_s=DocValuesFormat(name=Memory),
a_i=DocValuesFormat(name=Direct), test_i=DocValuesFormat(name=Lucene70),
join3_i=DocValuesFormat(name=Memory), field_i=DocValuesFormat(name=Lucene70),
level1_s=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Memory),
a_ss=DocValuesFormat(name=Lucene70), col_s=DocValuesFormat(name=Lucene70),
level2_s=DocValuesFormat(name=Lucene70), a_s=DocValuesFormat(name=Memory),
id=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene70),
i_multi=DocValuesFormat(name=Direct), b_ls=DocValuesFormat(name=Lucene70),
test_dt=DocValuesFormat(name=Direct), ident_s=DocValuesFormat(name=Lucene70),
order_i=DocValuesFormat(name=Lucene70),
_version_=DocValuesFormat(name=Lucene70), side_s=DocValuesFormat(name=Memory),
miles_i=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=2036,
maxMBSortInHeap=5.871593556214443, sim=RandomSimilarity(queryNorm=false): {},
locale=dz, timezone=CTT
[junit4] 2> NOTE: Linux 4.13.0-31-generic amd64/Oracle Corporation 10-ea
(64-bit)/cpus=8,threads=1,free=124813448,total=355467264
[junit4] 2> NOTE: All tests run in this JVM: [CloudSolrClientBuilderTest,
SolrExampleJettyTest, DocumentAnalysisResponseTest, ArrayEvaluatorTest,
JdbcDriverTest, SolrExampleXMLTest, SolrExampleBinaryTest, SolrParamTest,
HyperbolicSineEvaluatorTest, GetByIdTest, AddEvaluatorTest,
HttpSolrClientBuilderTest, ArcCosineEvaluatorTest, TestCoreAdmin,
CosineEvaluatorTest, RegressionEvaluatorTest, TestHash, TestBatchUpdate,
StreamExpressionToExplanationTest, TestValidatingJsonMap, TestJsonRecordReader,
PowerEvaluatorTest, LessThanEqualToEvaluatorTest, CoalesceEvaluatorTest,
SolrExceptionTest, SolrExampleStreamingBinaryTest, JettyWebappTest,
LargeVolumeEmbeddedTest, LargeVolumeJettyTest, TestSolrProperties,
MergeIndexesEmbeddedTest, TestEmbeddedSolrServer, TestNamedListCodec,
TestJavaBinCodec, ModifiableSolrParamsTest, ClientUtilsTest, NamedListTest,
UsingSolrJRefGuideExamplesTest, TestSolrJErrorHandling,
HttpSolrClientConPoolTest, LBHttpSolrClientBuilderTest,
TestCloudSolrClientConnections, JDBCStreamTest, StreamExpressionTest]
[junit4] Completed [146/146 (1!)] on J2 in 75.64s, 153 tests, 1 failure, 9
skipped <<< FAILURES!
[...truncated 39997 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
