You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/05/25 03:51:21 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4656
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4656/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
Error Message:
Timeout waiting for new leader null Live Nodes: [127.0.0.1:54576_solr, 127.0.0.1:54582_solr, 127.0.0.1:54588_solr] Last available state: DocCollection(collection1//collections/collection1/state.json/14)={ "pullReplicas":"0", "replicationFactor":"3", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{ "core_node62":{ "core":"collection1_shard1_replica_n61", "base_url":"http://127.0.0.1:54568/solr", "node_name":"127.0.0.1:54568_solr", "state":"down", "type":"NRT", "force_set_state":"false"}, "core_node64":{ "core":"collection1_shard1_replica_n63", "base_url":"http://127.0.0.1:54576/solr", "node_name":"127.0.0.1:54576_solr", "state":"active", "type":"NRT", "force_set_state":"false"}, "core_node66":{ "core":"collection1_shard1_replica_n65", "base_url":"http://127.0.0.1:54582/solr", "node_name":"127.0.0.1:54582_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timeout waiting for new leader
null
Live Nodes: [127.0.0.1:54576_solr, 127.0.0.1:54582_solr, 127.0.0.1:54588_solr]
Last available state: DocCollection(collection1//collections/collection1/state.json/14)={
"pullReplicas":"0",
"replicationFactor":"3",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node62":{
"core":"collection1_shard1_replica_n61",
"base_url":"http://127.0.0.1:54568/solr",
"node_name":"127.0.0.1:54568_solr",
"state":"down",
"type":"NRT",
"force_set_state":"false"},
"core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:54576/solr",
"node_name":"127.0.0.1:54576_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"},
"core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:54582/solr",
"node_name":"127.0.0.1:54582_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([E2917A40304F9AD3:4A8D66FAF20FAEF9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:278)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection(LeaderVoteWaitTimeoutTest.java:187)
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.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([E2917A40304F9AD3:10656D2274EA97E0]:0)
at org.apache.solr.client.solrj.embedded.JettySolrRunner.getNodeName(JettySolrRunner.java:347)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:93)
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 12302 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> 30039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/init-core-data-001
[junit4] 2> 30040 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 30040 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 30044 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 30054 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001
[junit4] 2> 30073 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 30078 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 30079 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 30289 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54539
[junit4] 2> 30410 ERROR (Thread-5) [ ] 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> 30540 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31235 INFO (jetty-launcher-9-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> 31235 INFO (jetty-launcher-9-thread-4) [ ] 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> 31235 INFO (jetty-launcher-9-thread-3) [ ] 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> 31235 INFO (jetty-launcher-9-thread-2) [ ] 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> 31297 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31297 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31309 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31310 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31315 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31316 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31316 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31331 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 31380 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 31380 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 31385 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3828b777{/solr,null,AVAILABLE}
[junit4] 2> 31386 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b895281{/solr,null,AVAILABLE}
[junit4] 2> 31392 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6bcb7cee{/solr,null,AVAILABLE}
[junit4] 2> 31393 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ab4a7ec{/solr,null,AVAILABLE}
[junit4] 2> 31440 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c25f2d7{HTTP/1.1,[http/1.1]}{127.0.0.1:54543}
[junit4] 2> 31441 INFO (jetty-launcher-9-thread-4) [ ] o.e.j.s.Server Started @31523ms
[junit4] 2> 31441 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54543}
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@719fa609{HTTP/1.1,[http/1.1]}{127.0.0.1:54544}
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.Server Started @31529ms
[junit4] 2> 31447 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54544}
[junit4] 2> 31449 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@324a31f4{HTTP/1.1,[http/1.1]}{127.0.0.1:54545}
[junit4] 2> 31450 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.Server Started @31532ms
[junit4] 2> 31450 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54545}
[junit4] 2> 31473 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@477795c4{HTTP/1.1,[http/1.1]}{127.0.0.1:54542}
[junit4] 2> 31474 INFO (jetty-launcher-9-thread-3) [ ] o.e.j.s.Server Started @31556ms
[junit4] 2> 31474 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54542}
[junit4] 2> 31535 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.u.c.SSLCredentialProviderFactory Processing SSL Credential Provider chain: env;sysprop
[junit4] 2> 31553 ERROR (jetty-launcher-9-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31553 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31553 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31554 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.974Z
[junit4] 2> 31555 ERROR (jetty-launcher-9-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31555 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.975Z
[junit4] 2> 31556 ERROR (jetty-launcher-9-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31556 ERROR (jetty-launcher-9-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.976Z
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 31556 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:32.976Z
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31564 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31581 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31581 INFO (zkConnectionManagerCallback-20-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31584 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31607 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31619 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 31670 INFO (jetty-launcher-9-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31671 INFO (jetty-launcher-9-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31671 INFO (jetty-launcher-9-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31682 INFO (jetty-launcher-9-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-32-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-34-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31726 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31727 INFO (zkConnectionManagerCallback-30-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31745 INFO (zkConnectionManagerCallback-38-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31745 INFO (zkConnectionManagerCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31747 INFO (zkConnectionManagerCallback-44-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 31747 INFO (zkConnectionManagerCallback-42-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 32232 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32232 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32233 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32233 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 32235 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54542_solr
[junit4] 2> 32239 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.Overseer Overseer (id=72083277747257354-127.0.0.1:54542_solr-n_0000000000) starting
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54545_solr
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54544_solr
[junit4] 2> 32240 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54543_solr
[junit4] 2> 32298 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32302 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32303 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32303 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32604 INFO (zkConnectionManagerCallback-57-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 32621 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 32627 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33017 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 33017 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54542_solr
[junit4] 2> 33022 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33022 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33023 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33024 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33025 INFO (OverseerStateUpdate-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 33492 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33493 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33493 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54545.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33499 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node1/.
[junit4] 2> 33505 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33518 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33518 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54544.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33520 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node2/.
[junit4] 2> 33562 INFO (zkConnectionManagerCallback-65-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33565 INFO (zkConnectionManagerCallback-67-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33574 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33575 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33577 INFO (jetty-launcher-9-thread-1) [n:127.0.0.1:54545_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33579 INFO (jetty-launcher-9-thread-2) [n:127.0.0.1:54544_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 33677 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33681 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33681 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54543.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33704 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33708 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33708 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54542.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 33709 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node4/.
[junit4] 2> 33709 INFO (jetty-launcher-9-thread-3) [n:127.0.0.1:54542_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node3/.
[junit4] 2> 33742 INFO (zkConnectionManagerCallback-72-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 33746 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 33748 INFO (jetty-launcher-9-thread-4) [n:127.0.0.1:54543_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34154 INFO (zkConnectionManagerCallback-74-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34164 INFO (zkConnectionManagerCallback-79-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34168 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 34172 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34206 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6c25f2d7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 34207 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=887163033
[junit4] 2> 34207 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 34208 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fafba28: rootName = solr_54543, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3b7be84c
[junit4] 2> 34215 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 34215 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ba5aba2: rootName = solr_54543, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 34227 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 34227 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b19aeb9: rootName = solr_54543, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 34231 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54543_solr
[junit4] 2> 34236 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34237 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34236 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-71-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34241 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34242 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34243 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 34243 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34260 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b895281{/solr,null,UNAVAILABLE}
[junit4] 2> 34260 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 34265 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] 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> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 34267 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c2cf71b{/solr,null,AVAILABLE}
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@58eb41e6{HTTP/1.1,[http/1.1]}{127.0.0.1:54569}
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @34351ms
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54568}
[junit4] 2> 34269 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 34269 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 34270 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:35.690Z
[junit4] 2> 34275 INFO (zkConnectionManagerCallback-81-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34277 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 34299 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 34305 INFO (zkConnectionManagerCallback-85-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34313 INFO (zkConnectionManagerCallback-87-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34334 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 34341 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 34347 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34347 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54568_solr
[junit4] 2> 34351 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34351 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34352 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34587 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34649 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34649 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54568.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 34651 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node4/.
[junit4] 2> 34659 INFO (zkConnectionManagerCallback-94-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34663 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 34666 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54568_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 34781 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54569/solr. Proxy: http://127.0.0.1:54568/solr
[junit4] 2> 34783 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@324a31f4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 34783 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=50675663
[junit4] 2> 34788 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 34789 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4cce411: rootName = solr_54545, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5165cb76
[junit4] 2> 34798 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 34798 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43039fba: rootName = solr_54545, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 34803 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 34803 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7fc21a8d: rootName = solr_54545, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 34807 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54545_solr
[junit4] 2> 34811 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34811 INFO (zkCallback-64-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34812 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 34814 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 34816 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34830 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4ab4a7ec{/solr,null,UNAVAILABLE}
[junit4] 2> 34830 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 34832 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] 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> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 34834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@59faf4a1{/solr,null,AVAILABLE}
[junit4] 2> 34835 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1853c2b9{HTTP/1.1,[http/1.1]}{127.0.0.1:54577}
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @34918ms
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54576}
[junit4] 2> 34836 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 34836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 34837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 34837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:36.257Z
[junit4] 2> 34840 INFO (zkConnectionManagerCallback-96-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34842 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 34863 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 34866 INFO (zkConnectionManagerCallback-100-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34873 INFO (zkConnectionManagerCallback-102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 34897 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 34906 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 34913 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 34913 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54576_solr
[junit4] 2> 34917 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34917 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34918 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34918 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34920 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 34921 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35208 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35221 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35222 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54576.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35229 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node1/.
[junit4] 2> 35236 INFO (zkConnectionManagerCallback-109-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35240 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 35244 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54576_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 35340 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54577/solr. Proxy: http://127.0.0.1:54576/solr
[junit4] 2> 35341 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@477795c4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 35342 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1719358010
[junit4] 2> 35342 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 35343 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f0b4e49: rootName = solr_54542, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2b2628c1
[junit4] 2> 35352 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 35352 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e206cb5: rootName = solr_54542, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 35358 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 35359 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fd12d24: rootName = solr_54542, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 35367 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54542_solr
[junit4] 2> 35372 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-56-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35373 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35377 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 35388 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 35394 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.Overseer Overseer (id=72083277747257354-127.0.0.1:54542_solr-n_0000000000) closing
[junit4] 2> 35395 INFO (OverseerStateUpdate-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:54542_solr
[junit4] 2> 35426 WARN (OverseerAutoScalingTriggerThread-72083277747257354-127.0.0.1:54542_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 35434 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54544_solr
[junit4] 2> 35435 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3828b777{/solr,null,UNAVAILABLE}
[junit4] 2> 35435 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 35437 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72083277747257353-127.0.0.1:54544_solr-n_0000000003) starting
[junit4] 2> 35438 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] 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> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 35440 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 35441 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@482a1383{/solr,null,AVAILABLE}
[junit4] 2> 35450 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2ca59ec8{HTTP/1.1,[http/1.1]}{127.0.0.1:54583}
[junit4] 2> 35450 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @35533ms
[junit4] 2> 35451 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54582}
[junit4] 2> 35466 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 35538 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 35539 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 35540 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:36.960Z
[junit4] 2> 35544 INFO (zkConnectionManagerCallback-111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35547 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 35564 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 35571 INFO (zkConnectionManagerCallback-115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35583 INFO (zkConnectionManagerCallback-117-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35601 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 35607 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 35617 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 35618 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54582_solr
[junit4] 2> 35623 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35624 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35625 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 35856 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35870 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35871 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54582.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 35873 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node3/.
[junit4] 2> 35886 INFO (zkConnectionManagerCallback-124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35890 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 35893 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54582_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 36028 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54583/solr. Proxy: http://127.0.0.1:54582/solr
[junit4] 2> 36037 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@719fa609{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 36038 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=539750970
[junit4] 2> 36039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 36039 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ef1c96: rootName = solr_54544, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3fd33e96
[junit4] 2> 36048 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 36048 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3c7a42c8: rootName = solr_54544, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2bfee2d9
[junit4] 2> 36058 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 36058 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5927816a: rootName = solr_54544, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2225df86
[junit4] 2> 36062 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54544_solr
[junit4] 2> 36066 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-66-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36067 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36068 INFO (zkCallback-123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 36070 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 36073 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 36080 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.Overseer Overseer (id=72083277747257353-127.0.0.1:54544_solr-n_0000000003) closing
[junit4] 2> 36081 INFO (OverseerStateUpdate-72083277747257353-127.0.0.1:54544_solr-n_0000000003) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:54544_solr
[junit4] 2> 36081 WARN (OverseerAutoScalingTriggerThread-72083277747257353-127.0.0.1:54544_solr-n_0000000003) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 36090 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54568_solr
[junit4] 2> 36093 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72083277747257365-127.0.0.1:54568_solr-n_0000000004) starting
[junit4] 2> 36094 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6bcb7cee{/solr,null,UNAVAILABLE}
[junit4] 2> 36095 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 36124 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] 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> 36144 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 36145 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 36145 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 36150 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1277798b{/solr,null,AVAILABLE}
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1078d988{HTTP/1.1,[http/1.1]}{127.0.0.1:54589}
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.e.j.s.Server Started @36237ms
[junit4] 2> 36154 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54588}
[junit4] 2> 36156 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 36156 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 36158 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T02:22:37.578Z
[junit4] 2> 36163 INFO (zkConnectionManagerCallback-126-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36165 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 36206 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54539/solr
[junit4] 2> 36240 INFO (zkConnectionManagerCallback-130-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36251 INFO (zkConnectionManagerCallback-132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36296 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 36302 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 36312 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 36312 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54588_solr
[junit4] 2> 36320 INFO (zkCallback-123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36320 INFO (zkCallback-78-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36321 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36321 INFO (zkCallback-131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 36638 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36673 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36673 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54588.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@297dba8a
[junit4] 2> 36694 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_E2917A40304F9AD3-001/tempDir-001/node2/.
[junit4] 2> 36708 INFO (zkConnectionManagerCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 36714 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 36721 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [n:127.0.0.1:54588_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54539/solr ready
[junit4] 2> 36847 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[E2917A40304F9AD3]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:54589/solr. Proxy: http://127.0.0.1:54588/solr
[junit4] 2> 36938 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[E2917A40304F9AD3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMostInSyncReplicasCanWinElection
[junit4] 2> 37447 INFO (SocketProxy-Acceptor-54568) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=54594,localport=54568], receiveBufferSize:408300
[junit4] 2> 37498 INFO (SocketProxy-Acceptor-54568) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=54569,localport=54595], receiveBufferSize=408300
[junit4] 2> 38538 INFO (qtp657487167-221) [n:127.0.0.1:54568_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=3&name=collection1&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 38707 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 38710 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 38716 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/collection1.AUTOCREATED/protwords.txt
[junit4] 2> 38721 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/collection1.AUTOCREATED/solrconfig.xml
[junit4] 2> 38725 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/collection1.AUTOCREATED/synonyms.txt
[junit4] 2> 38729 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/collection1.AUTOCREATED/stopwords.txt
[junit4] 2> 38756 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/collection1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 38765 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/collection1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 38774 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/collection1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 38784 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 38790 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/collection1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 38796 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/collection1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 38801 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/collection1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 38831 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 38835 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 38840 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 38844 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/collection1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 38855 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 38860 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/collection1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 38864 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 38868 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 38873 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 38877 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/collection1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 38881 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 38885 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/collection1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 38889 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/collection1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 38893 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/collection1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 38897 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/collection1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 38901 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/collection1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 38905 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 38910 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/collection1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 38914 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 38918 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 38922 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/collection1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 38926 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/collection1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 38930 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/collection1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 38934 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 38938 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 38942 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/collection1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 38946 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 38950 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 38956 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 38961 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/collection1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 38965 INFO (OverseerThreadFactory-120-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 38969 INFO (OverseerThreadFactory-120-thread-1) [ ]
[...truncated too long message...]
s/test
common-solr.compile-test:
compile-test:
BUILD SUCCESSFUL
Total time: 1 minute 54 seconds
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=E2917A40304F9AD3 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=US/Indiana-Starke -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
Buildfile: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/build.xml
-clover.disable:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/top-level-ivy-settings.xml
install-junit4-taskdef:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 31ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: E2917A40304F9AD3
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/temp
[junit4] <JUnit4> says ¡Hola! Master seed: E2917A40304F9AD3
[junit4] Executing 5 suites with 2 JVMs.
[junit4]
[junit4] Started J1 PID(77952@serv1-vm2.sd-datasolutions.de).
[junit4] Started J0 PID(77951@serv1-vm2.sd-datasolutions.de).
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 40.0s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 12.3s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [1/5] on J1 in 75.68s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 40.1s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 14.6s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [2/5] on J0 in 78.10s, 2 tests
[junit4]
[junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 18.6s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 11.7s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [3/5] on J0 in 36.23s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 19.0s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 16.0s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [4/5] on J1 in 43.21s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 17.0s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] OK 11.1s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] Completed [5/5] on J0 in 33.78s, 2 tests
[junit4]
[junit4] JVM J0: 1.01 .. 151.62 = 150.62s
[junit4] JVM J1: 1.00 .. 122.78 = 121.77s
[junit4] Execution time total: 2 minutes 31 seconds
[junit4] Tests summary: 5 suites, 10 tests
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 177.61s | org.apache.solr.cloud.autoscaling.SearchRateTriggerIntegrationTest
[junit4:tophints] 143.18s | org.apache.solr.cloud.api.collections.AssignTest
[junit4:tophints] 133.38s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 117.48s | org.apache.solr.update.SoftAutoCommitTest
[junit4:tophints] 113.63s | org.apache.solr.cloud.TestTlogReplica
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 2 minutes 36 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
+ 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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2678)
at hudson.FilePath$34.invoke(FilePath.java:2557)
at hudson.FilePath$34.invoke(FilePath.java:2547)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:1036)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1038)
at hudson.FilePath.act(FilePath.java:1025)
at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1819)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/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=ad143a1ceceb1e1b40780db2c65bf437e2abc87d, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4655
Recording test results
Setting ANT_1_8_2_HOME=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2