lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4656 - Still Unstable!
Date Fri, 25 May 2018 03:51:21 GMT
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

Mime
View raw message