Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1943/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseG1GC
3 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={
"id":"20a7b9e8016cT7yhhq6biyqaau4xh943gugwfk", "source":"node_added_trigger",
"eventTime":35904750616940, "eventType":"NODEADDED", "properties":{
"eventTimes":[ 35904750616940, 35904750618722,
35904750619454, 35904750620160], "nodeNames":[
"127.0.0.1:34251_solr", "127.0.0.1:39737_solr",
"127.0.0.1:40947_solr", "127.0.0.1:35517_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run!
event={
"id":"20a7b9e8016cT7yhhq6biyqaau4xh943gugwfk",
"source":"node_added_trigger",
"eventTime":35904750616940,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
35904750616940,
35904750618722,
35904750619454,
35904750620160],
"nodeNames":[
"127.0.0.1:34251_solr",
"127.0.0.1:39737_solr",
"127.0.0.1:40947_solr",
"127.0.0.1:35517_solr"]}}
at
__randomizedtesting.SeedInfo.seed([CB92C8C0EE93D50F:53C6C5316AAAD19]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={
"id":"20ad5ffda7f3T7yhhq6biyqaau4xh943gugwfo", "source":"node_added_trigger",
"eventTime":35929011890163, "eventType":"NODEADDED", "properties":{
"eventTimes":[ 35929011890163, 35929011892854,
35929011894271, 35929011895119], "nodeNames":[
"127.0.0.1:39977_solr", "127.0.0.1:35319_solr",
"127.0.0.1:39249_solr", "127.0.0.1:41907_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run!
event={
"id":"20ad5ffda7f3T7yhhq6biyqaau4xh943gugwfo",
"source":"node_added_trigger",
"eventTime":35929011890163,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
35929011890163,
35929011892854,
35929011894271,
35929011895119],
"nodeNames":[
"127.0.0.1:39977_solr",
"127.0.0.1:35319_solr",
"127.0.0.1:39249_solr",
"127.0.0.1:41907_solr"]}}
at
__randomizedtesting.SeedInfo.seed([CB92C8C0EE93D50F:53C6C5316AAAD19]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState
Error Message:
Did not expect the processor to fire on first run! event={
"id":"1df217aacc56T7yhhq6biyqaau4xh943gugxrl", "source":"node_added_trigger",
"eventTime":32925616360534, "eventType":"NODEADDED", "properties":{
"eventTimes":[ 32925616360534, 32925616361936,
32925616362380, 32925616362782], "nodeNames":[
"127.0.0.1:36107_solr", "127.0.0.1:39411_solr",
"127.0.0.1:42249_solr", "127.0.0.1:37599_solr"]}}
Stack Trace:
java.lang.AssertionError: Did not expect the processor to fire on first run!
event={
"id":"1df217aacc56T7yhhq6biyqaau4xh943gugxrl",
"source":"node_added_trigger",
"eventTime":32925616360534,
"eventType":"NODEADDED",
"properties":{
"eventTimes":[
32925616360534,
32925616361936,
32925616362380,
32925616362782],
"nodeNames":[
"127.0.0.1:36107_solr",
"127.0.0.1:39411_solr",
"127.0.0.1:42249_solr",
"127.0.0.1:37599_solr"]}}
at
__randomizedtesting.SeedInfo.seed([CB92C8C0EE93D50F:53C6C5316AAAD19]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
at
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13010 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/init-core-data-001
[junit4] 2> 663919 WARN
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 663919 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 663920 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 663921 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 663921 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001
[junit4] 2> 663921 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 663921 INFO (Thread-1078) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 663922 INFO (Thread-1078) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 663923 ERROR (Thread-1078) [ ] 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> 664022 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:41269
[junit4] 2> 664024 INFO (zkConnectionManagerCallback-1491-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664033 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 664033 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 664033 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 664033 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 664034 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2cbc2a{/solr,null,AVAILABLE}
[junit4] 2> 664036 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@18dd978{SSL,[ssl,
http/1.1]}{127.0.0.1:39411}
[junit4] 2> 664036 INFO (jetty-launcher-1488-thread-1) [ ]
o.e.j.s.Server Started @664073ms
[junit4] 2> 664036 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39411}
[junit4] 2> 664037 ERROR (jetty-launcher-1488-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 664037 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 664037 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 664037 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 664037 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 664037 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:29:55.048Z
[junit4] 2> 664038 INFO (zkConnectionManagerCallback-1493-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664039 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 664048 INFO (jetty-launcher-1488-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 664050 INFO (zkConnectionManagerCallback-1497-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664052 INFO (zkConnectionManagerCallback-1499-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664109 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 664109 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:39411_solr
[junit4] 2> 664110 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.c.Overseer Overseer
(id=72059750606045187-127.0.0.1:39411_solr-n_0000000000) starting
[junit4] 2> 664115 INFO (zkConnectionManagerCallback-1506-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 664117 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 664117 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39411_solr
[junit4] 2> 664118 INFO
(OverseerStateUpdate-72059750606045187-127.0.0.1:39411_solr-n_0000000000) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 664123 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 665612 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39411.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 665619 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39411.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 665619 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39411.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 665621 INFO (jetty-launcher-1488-thread-1)
[n:127.0.0.1:39411_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node1/.
[junit4] 2> 665636 INFO (zkConnectionManagerCallback-1508-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665640 INFO (zkConnectionManagerCallback-1513-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665641 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 665642 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41269/solr ready
[junit4] 2> 665651 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.SolrTestCaseJ4 ###Starting testListenerAcceptance
[junit4] 2> 665658 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 665659 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 665659 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 665659 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 665659 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@18af018{/solr,null,AVAILABLE}
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.AbstractConnector Started ServerConnector@17a2695{SSL,[ssl,
http/1.1]}{127.0.0.1:37599}
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.e.j.s.Server Started @665696ms
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37599}
[junit4] 2> 665660 ERROR
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 665660 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:29:56.671Z
[junit4] 2> 665662 INFO (zkConnectionManagerCallback-1515-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665663 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 665672 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 665673 INFO (zkConnectionManagerCallback-1519-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665686 INFO (zkConnectionManagerCallback-1521-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 665692 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 665693 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 665695 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 665695 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37599_solr
[junit4] 2> 665696 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 665696 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 665697 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 665697 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 666485 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37599.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 666495 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37599.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 666495 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37599.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 666496 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node2/.
[junit4] 2> 666499 INFO (zkConnectionManagerCallback-1528-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666501 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 666501 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:37599_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 666526 INFO
(TEST-NodeAddedTriggerTest.testListenerAcceptance-seed#[CB92C8C0EE93D50F]) [
] o.a.s.SolrTestCaseJ4 ###Ending testListenerAcceptance
[junit4] 2> 666537 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testTrigger
[junit4] 2> 666540 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 666541 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 666541 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 666541 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 666542 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@30be91{/solr,null,AVAILABLE}
[junit4] 2> 666542 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@913e09{SSL,[ssl,
http/1.1]}{127.0.0.1:36107}
[junit4] 2> 666542 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server Started @666579ms
[junit4] 2> 666542 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36107}
[junit4] 2> 666542 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 666543 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 666543 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 666543 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 666543 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 666543 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:29:57.554Z
[junit4] 2> 666544 INFO (zkConnectionManagerCallback-1530-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666545 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 666553 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 666554 INFO (zkConnectionManagerCallback-1534-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666556 INFO (zkConnectionManagerCallback-1536-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 666563 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 666564 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 666566 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 666566 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36107_solr
[junit4] 2> 666567 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666567 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666567 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666567 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666569 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 666569 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 667568 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36107.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 667577 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36107.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 667577 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36107.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 667579 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node3/.
[junit4] 2> 667582 INFO (zkConnectionManagerCallback-1543-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 667584 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 667585 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:36107_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 667618 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 667619 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 667619 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 667619 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 667620 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3754d2{/solr,null,AVAILABLE}
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1fbc605{SSL,[ssl,
http/1.1]}{127.0.0.1:42249}
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server Started @667657ms
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42249}
[junit4] 2> 667621 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 667621 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:29:58.632Z
[junit4] 2> 667623 INFO (zkConnectionManagerCallback-1545-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 667623 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 667633 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 667634 INFO (zkConnectionManagerCallback-1549-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 667636 INFO (zkConnectionManagerCallback-1551-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 667643 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 667645 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 667647 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 667647 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42249_solr
[junit4] 2> 667648 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667648 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 667649 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 670087 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42249.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 670099 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42249.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 670099 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42249.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 670100 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node4/.
[junit4] 2> 670104 INFO (zkConnectionManagerCallback-1558-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 670105 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 670107 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42249_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 676152 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 676154 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 676154 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 676154 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 676154 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1b4811e{/solr,null,AVAILABLE}
[junit4] 2> 676155 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1c8eac7{SSL,[ssl,
http/1.1]}{127.0.0.1:42979}
[junit4] 2> 676155 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server Started @676192ms
[junit4] 2> 676155 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42979}
[junit4] 2> 676155 ERROR
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 676155 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 676156 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 676156 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 676156 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 676156 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:30:07.167Z
[junit4] 2> 676157 INFO (zkConnectionManagerCallback-1560-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 676158 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 676166 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 676168 INFO (zkConnectionManagerCallback-1564-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 676170 INFO (zkConnectionManagerCallback-1566-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 676176 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 676178 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 676179 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 676180 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42979_solr
[junit4] 2> 676181 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676181 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676183 INFO (zkCallback-1565-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676183 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 676184 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 677788 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42979.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 677797 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42979.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 677797 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42979.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 677799 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node5/.
[junit4] 2> 677802 INFO (zkConnectionManagerCallback-1573-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 677804 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 677805 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:42979_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 677855 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1c8eac7{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 677856 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=28542531
[junit4] 2> 677856 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 677856 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1ac66b: rootName =
solr_42979, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@11f582e
[junit4] 2> 677862 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 677862 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b61aef: rootName =
solr_42979, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 677865 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 677866 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@188ce6b: rootName =
solr_42979, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 677867 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42979_solr
[junit4] 2> 677868 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 677868 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677868 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677869 INFO (zkCallback-1565-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677870 INFO (zkCallback-1572-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 677870 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1b4811e{/solr,null,UNAVAILABLE}
[junit4] 2> 677870 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 682873 INFO
(TEST-NodeAddedTriggerTest.testTrigger-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testTrigger
[junit4] 2> 682896 INFO
(TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testActionLifecycle
[junit4] 2> 682898 INFO
(TEST-NodeAddedTriggerTest.testActionLifecycle-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testActionLifecycle
[junit4] 2> 682920 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testRestoreState
[junit4] 2> 682922 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11
[junit4] 2> 682923 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 682923 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 682923 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 682924 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1a95861{/solr,null,AVAILABLE}
[junit4] 2> 682924 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@174e99b{SSL,[ssl,
http/1.1]}{127.0.0.1:41765}
[junit4] 2> 682924 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.e.j.s.Server Started @682961ms
[junit4] 2> 682924 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41765}
[junit4] 2> 682925 ERROR
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 682925 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 682925 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 682925 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 682925 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 682925 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-20T21:30:13.936Z
[junit4] 2> 682927 INFO (zkConnectionManagerCallback-1575-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682928 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 682939 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41269/solr
[junit4] 2> 682940 INFO (zkConnectionManagerCallback-1579-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682942 INFO (zkConnectionManagerCallback-1581-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 682947 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 682948 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 682950 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 682950 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41765_solr
[junit4] 2> 682951 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1512-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682951 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682952 INFO (zkCallback-1580-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 682952 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 684856 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41765.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 684866 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41765.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 684866 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41765.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@c29058
[junit4] 2> 684867 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_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.NodeAddedTriggerTest_CB92C8C0EE93D50F-001/tempDir-001/node6/.
[junit4] 2> 684871 INFO (zkConnectionManagerCallback-1588-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 684873 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (5)
[junit4] 2> 684874 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F])
[n:127.0.0.1:41765_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:41269/solr ready
[junit4] 2> 684931 INFO
(TEST-NodeAddedTriggerTest.testRestoreState-seed#[CB92C8C0EE93D50F]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testRestoreState
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=NodeAddedTriggerTest -Dtests.method=testRestoreState
-Dtests.seed=CB92C8C0EE93D50F -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=mt-MT -Dtests.timezone=America/Argentina/Jujuy
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 2.03s J2 | NodeAddedTriggerTest.testRestoreState <<<
[junit4] > Throwable #1: java.lang.AssertionError: Did not expect the
processor to fire on first run! event={
[junit4] > "id":"1df217aacc56T7yhhq6biyqaau4xh943gugxrl",
[junit4] > "source":"node_added_trigger",
[junit4] > "eventTime":32925616360534,
[junit4] > "eventType":"NODEADDED",
[junit4] > "properties":{
[junit4] > "eventTimes":[
[junit4] > 32925616360534,
[junit4] > 32925616361936,
[junit4] > 32925616362380,
[junit4] > 32925616362782],
[junit4] > "nodeNames":[
[junit4] > "127.0.0.1:36107_solr",
[junit4] > "127.0.0.1:39411_solr",
[junit4] > "127.0.0.1:42249_solr",
[junit4] > "127.0.0.1:37599_solr"]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([CB92C8C0EE93D50F:53C6C5316AAAD19]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.lambda$new$0(NodeAddedTriggerTest.java:49)
[junit4] > at
org.apache.solr.cloud.autoscaling.NodeAddedTrigger.run(NodeAddedTrigger.java:161)
[junit4] > at
org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest.testRestoreState(NodeAddedTriggerTest.java:257)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 684936 INFO (jetty-closer-1489-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@913e09{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 684936 INFO (jetty-closer-1489-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@17a2695{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 684936 INFO (jetty-closer-1489-thread-4) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1fbc605{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@18dd978{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=6343625
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=10611535
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=8515651
[junit4] 2> 684937 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=6569640
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@892772: rootName =
solr_42249, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1a50e61
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-6) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@174e99b{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7e616a: rootName =
solr_36107, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@e5db22
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7a85cb: rootName =
solr_37599, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@ec4925
[junit4] 2> 684938 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3010ab: rootName =
solr_39411, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@12e8ca3
[junit4] 2> 684939 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=8557789
[junit4] 2> 684939 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 684939 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@e61b0f: rootName =
solr_41765, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@7f7d10
[junit4] 2> 684949 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 684949 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f1e37f: rootName =
solr_39411, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 684949 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 684949 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@174edf: rootName =
solr_42249, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 684950 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 684950 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@d12cfa: rootName =
solr_36107, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 684951 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 684951 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@316b41: rootName =
solr_41765, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 684951 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 684951 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1e283e9: rootName =
solr_37599, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@aa5467
[junit4] 2> 684954 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 684954 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 684954 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@10c69e9: rootName =
solr_42249, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 684954 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@132728f: rootName =
solr_39411, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 684955 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:39411_solr
[junit4] 2> 684955 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42249_solr
[junit4] 2> 684955 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 684956 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@193bc71: rootName =
solr_36107, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 684956 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 684956 INFO (jetty-closer-1489-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 684956 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 684957 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 684957 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 684957 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684957 INFO (zkCallback-1580-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684957 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 684957 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684957 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1ed9a43: rootName =
solr_41765, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 684957 INFO (zkCallback-1542-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 684957 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684957 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684957 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684958 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 684958 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 684958 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1e23330: rootName =
solr_37599, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@36d828
[junit4] 2> 684959 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:36107_solr
[junit4] 2> 684959 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:41765_solr
[junit4] 2> 684959 INFO (jetty-closer-1489-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 684960 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684960 INFO (zkCallback-1520-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684960 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684960 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684960 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37599_solr
[junit4] 2> 684960 INFO (zkCallback-1505-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684961 INFO (jetty-closer-1489-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72059750606045187-127.0.0.1:39411_solr-n_0000000000) closing
[junit4] 2> 684961 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 684961 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 684961 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 684961 INFO (zkCallback-1587-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 684961 INFO (zkCallback-1587-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
[junit4] 2> 684961 INFO
(OverseerStateUpdate-72059750606045187-127.0.0.1:39411_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39411_solr
[junit4] 2> 684961 INFO (zkCallback-1535-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 684961 INFO (jetty-closer-1489-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 684961 INFO (zkCallback-1580-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 684962 INFO (zkCallback-1550-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
[junit4] 2> 684962 INFO (jetty-closer-1489-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 684962 INFO (zkCallback-1580-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 684962 WARN
(OverseerAutoScalingTriggerThread-72059750606045187-127.0.0.1:39411_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 684963 INFO (zkCallback-1498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684963 INFO (zkCallback-1527-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684963 INFO (zkCallback-1550-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684963 INFO (zkCallback-1557-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684963 INFO (zkCallback-1505-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 684963 INFO (zkCallback-1542-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (0)
[junit4] 2> 684964 INFO (zkCallback-1520-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 684964 INFO (zkCallback-1587-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684965 INFO (zkCallback-1535-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 684965 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10001f61d7a0012, likely client has closed socket
[junit4] 2> 684966 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10001f61d7a0004, likely client has closed socket
[junit4] 2> 684966 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10001f61d7a0011, likely client has closed socket
[junit4] 2> 684967 INFO (jetty-closer-1489-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@30be91{/solr,null,UNAVAILABLE}
[junit4] 2> 684967 INFO (jetty-closer-1489-thread-3) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 684968 INFO (jetty-closer-1489-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2cbc2a{/solr,null,UNAVAILABLE}
[junit4] 2> 684968 INFO (jetty-closer-1489-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 684968 INFO (jetty-closer-1489-thread-6) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1a95861{/solr,null,UNAVAILABLE}
[junit4] 2> 684969 INFO (jetty-closer-1489-thread-6) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 686466 WARN (zkCallback-1550-thread-2) [ ]
o.a.s.c.ZkController Unable to register nodeLost path for 127.0.0.1:41765_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:41765_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:786)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:365)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:365)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:889)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$3(ZkStateReader.java:792)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:791)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$1400(ZkStateReader.java:73)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1284)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1279)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269)
~[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 686467 INFO (jetty-closer-1489-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3754d2{/solr,null,UNAVAILABLE}
[junit4] 2> 686468 INFO (jetty-closer-1489-thread-4) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 686468 WARN (zkCallback-1520-thread-2) [ ]
o.a.s.c.ZkController Unable to register nodeLost path for 127.0.0.1:41765_solr
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /autoscaling/nodeLost/127.0.0.1:41765_solr
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:786)
~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:365)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:365)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.ZkController.lambda$registerLiveNodesListener$2(ZkController.java:889)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.lambda$refreshLiveNodes$3(ZkStateReader.java:792)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ConcurrentHashMap$KeySetView.forEach(ConcurrentHashMap.java:4649)
~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:791)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.access$1400(ZkStateReader.java:73)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.refreshAndWatch(ZkStateReader.java:1284)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LiveNodeWatcher.process(ZkStateReader.java:1279)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:269)
~[java/:?]
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
~[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192)
~[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_172]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_172]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 686469 INFO (jetty-closer-1489-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@18af018{/solr,null,UNAVAILABLE}
[junit4] 2> 686469 INFO (jetty-closer-1489-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 686470 ERROR
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-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> 686470 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:41269 41269
[junit4] 2> 686675 INFO (Thread-1078) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:41269 41269
[junit4] 2> 686676 WARN (Thread-1078) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/72059750606045201-127.0.0.1:42249_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 13 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CB92C8C0EE93D50F-001
[junit4] 2> May 20, 2018 9:30:17 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1059, maxMBSortInHeap=5.902580973775229,
sim=RandomSimilarity(queryNorm=true): {}, locale=mt-MT,
timezone=America/Argentina/Jujuy
[junit4] 2> NOTE: Linux 4.13.0-41-generic i386/Oracle Corporation
1.8.0_172 (32-bit)/cpus=8,threads=1,free=302465112,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestFastWriter,
CoreAdminOperationTest, TestSolrDeletionPolicy2, TestDelegationWithHadoopAuth,
IgnoreLargeDocu
[...truncated too long message...]
2059947404492803-127.0.0.1:35319_solr-n_0000000000) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are
closed, exiting.
[junit4] 2> 49924 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000223ef940004, likely client has closed socket
[junit4] 2> 49929 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39249_solr
[junit4] 2> 49930 INFO (jetty-closer-103-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 49930 INFO (jetty-closer-103-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@a7e8d4: rootName =
solr_39249, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1fc98a8
[junit4] 2> 49930 INFO (jetty-closer-103-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 49930 INFO (jetty-closer-103-thread-6) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4a94ee: rootName =
solr_34369, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1fc98a8
[junit4] 2> 49931 INFO (jetty-closer-103-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@cb97e0{/solr,null,UNAVAILABLE}
[junit4] 2> 49931 INFO (jetty-closer-103-thread-1) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 49931 INFO (jetty-closer-103-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 49931 INFO (jetty-closer-103-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:39249_solr
[junit4] 2> 49931 INFO (jetty-closer-103-thread-4) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1ab7e9c: rootName =
solr_41907, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1fc98a8
[junit4] 2> 49932 INFO (jetty-closer-103-thread-6) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:34369_solr
[junit4] 2> 49932 INFO (jetty-closer-103-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 49933 INFO (zkCallback-171-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 49933 INFO (zkCallback-194-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 49933 INFO (zkCallback-164-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 49933 INFO (jetty-closer-103-thread-6) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 49933 INFO (zkCallback-156-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 49933 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 49934 INFO (jetty-closer-103-thread-4) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:41907_solr
[junit4] 2> 49934 INFO (zkCallback-171-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 49934 INFO (zkCallback-201-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 49934 INFO (jetty-closer-103-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 49935 INFO (zkCallback-171-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 49937 INFO (zkCallback-194-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 49937 INFO (zkCallback-156-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 49937 INFO (zkCallback-201-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 49937 INFO (zkCallback-164-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 49937 INFO (zkCallback-149-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 49938 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000223ef940019, likely client has closed socket
[junit4] 2> 49939 INFO (zkCallback-164-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41907_solr
[junit4] 2> 49939 INFO (jetty-closer-103-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@19cdfea{/solr,null,UNAVAILABLE}
[junit4] 2> 49940 INFO (jetty-closer-103-thread-3) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 49940 INFO (jetty-closer-103-thread-6) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@158b2af{/solr,null,UNAVAILABLE}
[junit4] 2> 49940 INFO (jetty-closer-103-thread-6) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 51441 INFO (jetty-closer-103-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@236628{/solr,null,UNAVAILABLE}
[junit4] 2> 51441 INFO (jetty-closer-103-thread-4) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 51442 ERROR
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-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> 51442 INFO
(SUITE-NodeAddedTriggerTest-seed#[CB92C8C0EE93D50F]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43259 43259
[junit4] 2> 52059 INFO (Thread-61) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:43259 43259
[junit4] 2> 52060 WARN (Thread-61) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/72059947404492803-127.0.0.1:35319_solr-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72059947404492817-127.0.0.1:41907_solr-n_0000000003
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.NodeAddedTriggerTest_CB92C8C0EE93D50F-002
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1059, maxMBSortInHeap=5.902580973775229,
sim=RandomSimilarity(queryNorm=true): {}, locale=mt-MT,
timezone=America/Argentina/Jujuy
[junit4] 2> NOTE: Linux 4.13.0-41-generic i386/Oracle Corporation
1.8.0_172 (32-bit)/cpus=8,threads=1,free=38369752,total=101711872
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest,
NodeAddedTriggerTest]
[junit4] Completed [5/5 (3!)] on J0 in 23.75s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 13 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1568: The
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/lucene/common-build.xml:1092:
There were test failures: 5 suites, 20 tests, 3 failures [seed:
CB92C8C0EE93D50F]
Total time: 54 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 3/5 failed: org.apache.solr.cloud.autoscaling.NodeAddedTriggerTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=1e661ed97aed0cc77869b01134d80c761c6b5295,
workspace=/var/lib/jenkins/workspace/Lucene-Solr-7.x-Linux
[WARNINGS] Computing warning deltas based on reference build #1942
Recording test results
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
