You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/04/06 15:56:47 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_172) - Build #
23875 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/23875/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Timeout occurred while waiting response from server at: https://127.0.0.1:32979/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:32979/solr
at __randomizedtesting.SeedInfo.seed([8D66CBF2891768D4:7F92DC90CDB265E7]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:660)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1055)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:830)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:763)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:155)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
at sun.security.ssl.InputRecord.read(InputRecord.java:503)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:940)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:548)
... 49 more
Build Log:
[...truncated 14435 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> 2328283 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[8D66CBF2891768D4]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/init-core-data-001
[junit4] 2> 2328284 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[8D66CBF2891768D4]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=39 numCloses=39
[junit4] 2> 2328284 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[8D66CBF2891768D4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2328286 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[8D66CBF2891768D4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2328290 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMostInSyncReplicasCanWinElection
[junit4] 2> 2328291 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001
[junit4] 2> 2328291 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2328292 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2328292 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2328392 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkTestServer start zk server on port:36111
[junit4] 2> 2328392 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36111
[junit4] 2> 2328392 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36111
[junit4] 2> 2328395 INFO (zkConnectionManagerCallback-9535-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328397 INFO (zkConnectionManagerCallback-9537-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328401 INFO (zkConnectionManagerCallback-9539-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328407 WARN (jetty-launcher-9540-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2328407 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2328407 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2328407 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2328410 WARN (jetty-launcher-9540-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2328410 WARN (jetty-launcher-9540-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2328411 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2328418 WARN (jetty-launcher-9540-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2328419 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2328419 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2328419 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2328440 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2328440 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2328440 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2328441 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2328441 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2328441 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2328445 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2328445 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2328445 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2328446 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17e0ca4{/solr,null,AVAILABLE}
[junit4] 2> 2328447 WARN (jetty-launcher-9540-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5cf441[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2328447 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6741de{/solr,null,AVAILABLE}
[junit4] 2> 2328447 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2328447 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2328447 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2328448 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@55c3d9{/solr,null,AVAILABLE}
[junit4] 2> 2328448 WARN (jetty-launcher-9540-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ca0356[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2328448 WARN (jetty-launcher-9540-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1dcbf8c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4bd7c2{SSL,[ssl, http/1.1]}{127.0.0.1:34933}
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a0e836{/solr,null,AVAILABLE}
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.e.j.s.Server Started @2328550ms
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34933}
[junit4] 2> 2328450 ERROR (jetty-launcher-9540-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2328450 WARN (jetty-launcher-9540-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ee58aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:50.503Z
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c8b23e{SSL,[ssl, http/1.1]}{127.0.0.1:41957}
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-1) [ ] o.e.j.s.Server Started @2328550ms
[junit4] 2> 2328450 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41957}
[junit4] 2> 2328451 ERROR (jetty-launcher-9540-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2328451 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2328451 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2328451 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2328451 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2328451 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:50.504Z
[junit4] 2> 2328457 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@178273b{SSL,[ssl, http/1.1]}{127.0.0.1:37905}
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.e.j.s.Server Started @2328558ms
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37905}
[junit4] 2> 2328458 INFO (zkConnectionManagerCallback-9542-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328458 ERROR (jetty-launcher-9540-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:50.511Z
[junit4] 2> 2328458 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ebb7d0{SSL,[ssl, http/1.1]}{127.0.0.1:43549}
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.e.j.s.Server Started @2328570ms
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43549}
[junit4] 2> 2328470 ERROR (jetty-launcher-9540-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2328470 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:50.523Z
[junit4] 2> 2328473 INFO (zkConnectionManagerCallback-9544-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328474 INFO (zkConnectionManagerCallback-9546-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328474 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2328474 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2328487 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100f69ee26f0005, likely client has closed socket
[junit4] 2> 2328497 INFO (zkConnectionManagerCallback-9548-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2328498 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2329545 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2329546 WARN (jetty-launcher-9540-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2329553 WARN (jetty-launcher-9540-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1467b25[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2329571 WARN (jetty-launcher-9540-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2329592 WARN (jetty-launcher-9540-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@a42a00[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2329594 INFO (jetty-launcher-9540-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2329630 INFO (zkConnectionManagerCallback-9558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2329685 INFO (zkConnectionManagerCallback-9560-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2329787 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37905_solr
[junit4] 2> 2329788 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.c.Overseer Overseer (id=72328756302118920-127.0.0.1:37905_solr-n_0000000000) starting
[junit4] 2> 2329817 INFO (zkConnectionManagerCallback-9567-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2329833 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2329850 INFO (OverseerStateUpdate-72328756302118920-127.0.0.1:37905_solr-n_0000000000) [n:127.0.0.1:37905_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37905_solr
[junit4] 2> 2329871 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37905_solr
[junit4] 2> 2329882 INFO (zkCallback-9559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2329918 INFO (zkCallback-9566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2329987 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2330022 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37905.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330034 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37905.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330034 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37905.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330037 INFO (jetty-launcher-9540-thread-2) [n:127.0.0.1:37905_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node2/.
[junit4] 2> 2330606 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2330608 WARN (jetty-launcher-9540-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2330610 WARN (jetty-launcher-9540-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@389c78[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2330639 WARN (jetty-launcher-9540-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2330662 WARN (jetty-launcher-9540-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@eea927[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2330664 INFO (jetty-launcher-9540-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2330720 INFO (zkConnectionManagerCallback-9574-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2330753 INFO (zkConnectionManagerCallback-9576-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2330756 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2330759 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41957_solr as DOWN
[junit4] 2> 2330759 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2330759 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41957_solr
[junit4] 2> 2330764 INFO (zkCallback-9559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2330764 INFO (zkCallback-9566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2330780 INFO (zkCallback-9575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2330839 INFO (zkConnectionManagerCallback-9583-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2330842 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2330843 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2330889 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2330936 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41957.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330973 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41957.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330973 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41957.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2330977 INFO (jetty-launcher-9540-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node1/.
[junit4] 2> 2331344 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2331345 WARN (jetty-launcher-9540-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2331345 WARN (jetty-launcher-9540-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19dbf6d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2331350 WARN (jetty-launcher-9540-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2331358 WARN (jetty-launcher-9540-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@18babe1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2331360 INFO (jetty-launcher-9540-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2331381 INFO (zkConnectionManagerCallback-9590-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2331390 INFO (zkConnectionManagerCallback-9592-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2331393 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2331396 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:43549_solr as DOWN
[junit4] 2> 2331396 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2331396 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43549_solr
[junit4] 2> 2331397 INFO (zkCallback-9566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2331398 INFO (zkCallback-9559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2331398 INFO (zkCallback-9575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2331399 INFO (zkCallback-9582-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2331418 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2331432 INFO (zkConnectionManagerCallback-9599-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2331433 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2331434 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2331437 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2331462 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43549.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2331479 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43549.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2331479 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43549.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2331480 INFO (jetty-launcher-9540-thread-4) [n:127.0.0.1:43549_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node4/.
[junit4] 2> 2332643 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2332644 WARN (jetty-launcher-9540-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2332646 WARN (jetty-launcher-9540-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1a21b81[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2332649 WARN (jetty-launcher-9540-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2332654 WARN (jetty-launcher-9540-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@a3e974[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2332655 INFO (jetty-launcher-9540-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2332660 INFO (zkConnectionManagerCallback-9606-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2332661 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100f69ee26f0010, likely client has closed socket
[junit4] 2> 2332665 INFO (zkConnectionManagerCallback-9608-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2332668 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2332672 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34933_solr as DOWN
[junit4] 2> 2332673 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2332673 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34933_solr
[junit4] 2> 2332674 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332674 INFO (zkCallback-9566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332674 INFO (zkCallback-9575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332674 INFO (zkCallback-9559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332678 INFO (zkCallback-9582-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332678 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332678 INFO (zkCallback-9598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2332696 INFO (zkConnectionManagerCallback-9615-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2332697 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2332698 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2332701 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2332725 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34933.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2332741 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34933.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2332741 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34933.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2332742 INFO (jetty-launcher-9540-thread-3) [n:127.0.0.1:34933_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node3/.
[junit4] 2> 2332819 INFO (zkConnectionManagerCallback-9621-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2332820 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2332821 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2332823 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=12760334
[junit4] 2> 2332823 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37905_solr
[junit4] 2> 2332824 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 2332824 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37905_solr as DOWN
[junit4] 2> 2332826 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2332826 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2332826 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@cbf51d: rootName = solr_37905, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@c3a214
[junit4] 2> 2332835 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2332835 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d9495: rootName = solr_37905, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d352e8
[junit4] 2> 2332841 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2332842 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9e895: rootName = solr_37905, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2a43fc
[junit4] 2> 2332842 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2332846 INFO (closeThreadPool-9624-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72328756302118920-127.0.0.1:37905_solr-n_0000000000) closing
[junit4] 2> 2332850 INFO (OverseerStateUpdate-72328756302118920-127.0.0.1:37905_solr-n_0000000000) [n:127.0.0.1:37905_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37905_solr
[junit4] 2> 2332850 INFO (OverseerAutoScalingTriggerThread-72328756302118920-127.0.0.1:37905_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2332863 INFO (closeThreadPool-9624-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72328756302118920-127.0.0.1:37905_solr-n_0000000000) closing
[junit4] 2> 2332864 INFO (zkCallback-9575-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41957_solr
[junit4] 2> 2332864 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332864 INFO (zkCallback-9582-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332864 INFO (zkCallback-9575-thread-1) [n:127.0.0.1:41957_solr ] o.a.s.c.Overseer Overseer (id=72328756302118923-127.0.0.1:41957_solr-n_0000000001) starting
[junit4] 2> 2332867 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332867 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.Overseer Overseer (id=72328756302118920-127.0.0.1:37905_solr-n_0000000000) closing
[junit4] 2> 2332870 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332874 INFO (zkCallback-9598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332874 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332874 INFO (zkCallback-9575-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2332878 INFO (OverseerStateUpdate-72328756302118923-127.0.0.1:41957_solr-n_0000000001) [n:127.0.0.1:41957_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41957_solr
[junit4] 2> 2332887 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@178273b{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2332887 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@55c3d9{/solr,null,UNAVAILABLE}
[junit4] 2> 2332890 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2332890 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2332891 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2332893 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2332893 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2332913 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2332913 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2332914 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2332915 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64b51f{/solr,null,AVAILABLE}
[junit4] 2> 2332916 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@290d9f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2332919 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1999d8d{SSL,[ssl, http/1.1]}{127.0.0.1:42197}
[junit4] 2> 2332919 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server Started @2333019ms
[junit4] 2> 2332919 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41295}
[junit4] 2> 2332920 ERROR (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2332920 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2332920 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2332920 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2332920 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2332920 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:54.973Z
[junit4] 2> 2332930 INFO (zkConnectionManagerCallback-9626-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2332931 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2333778 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2333779 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2333780 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@9e70d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2333791 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2333793 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1bfc2b1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2333794 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2333802 INFO (zkConnectionManagerCallback-9633-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2333814 INFO (zkConnectionManagerCallback-9635-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2333816 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2333819 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41295_solr as DOWN
[junit4] 2> 2333820 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2333820 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41295_solr
[junit4] 2> 2333822 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333822 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333822 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333822 INFO (zkCallback-9582-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333822 INFO (zkCallback-9598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333823 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333823 INFO (zkCallback-9575-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333830 INFO (zkCallback-9634-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2333842 INFO (zkConnectionManagerCallback-9642-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2333844 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2333845 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2333850 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2333878 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41295.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2333896 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41295.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2333897 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41295.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2333898 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [n:127.0.0.1:41295_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node2/.
[junit4] 2> 2333981 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: https://127.0.0.1:42197/solr. Proxy: https://127.0.0.1:41295/solr
[junit4] 2> 2333981 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5964260
[junit4] 2> 2333981 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:41957_solr
[junit4] 2> 2333981 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 2333981 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:41957_solr as DOWN
[junit4] 2> 2333984 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2333984 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1778903: rootName = solr_41957, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1548458
[junit4] 2> 2333993 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2333993 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12ff348: rootName = solr_41957, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d352e8
[junit4] 2> 2334013 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2334013 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@69e15f: rootName = solr_41957, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2a43fc
[junit4] 2> 2334013 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2334022 INFO (closeThreadPool-9646-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72328756302118923-127.0.0.1:41957_solr-n_0000000001) closing
[junit4] 2> 2334036 INFO (OverseerStateUpdate-72328756302118923-127.0.0.1:41957_solr-n_0000000001) [n:127.0.0.1:41957_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41957_solr
[junit4] 2> 2334036 INFO (OverseerAutoScalingTriggerThread-72328756302118923-127.0.0.1:41957_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2334038 INFO (closeThreadPool-9646-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72328756302118923-127.0.0.1:41957_solr-n_0000000001) closing
[junit4] 2> 2334038 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.Overseer Overseer (id=72328756302118923-127.0.0.1:41957_solr-n_0000000001) closing
[junit4] 2> 2334039 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100f69ee26f000b, likely client has closed socket
[junit4] 2> 2334039 INFO (zkCallback-9598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334039 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334039 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334039 INFO (zkCallback-9634-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334039 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43549_solr
[junit4] 2> 2334039 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334041 INFO (zkCallback-9591-thread-1) [n:127.0.0.1:43549_solr ] o.a.s.c.Overseer Overseer (id=72328756302118926-127.0.0.1:43549_solr-n_0000000002) starting
[junit4] 2> 2334042 INFO (zkCallback-9641-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334047 INFO (zkCallback-9591-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2334047 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c8b23e{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2334050 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6741de{/solr,null,UNAVAILABLE}
[junit4] 2> 2334054 INFO (OverseerStateUpdate-72328756302118926-127.0.0.1:43549_solr-n_0000000002) [n:127.0.0.1:43549_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43549_solr
[junit4] 2> 2334062 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2334062 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2334063 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2334064 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2334064 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2334083 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2334083 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2334083 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2334083 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30e3b{/solr,null,AVAILABLE}
[junit4] 2> 2334084 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7412f6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2334085 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@194fe0e{SSL,[ssl, http/1.1]}{127.0.0.1:40163}
[junit4] 2> 2334085 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server Started @2334185ms
[junit4] 2> 2334085 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36857}
[junit4] 2> 2334085 ERROR (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2334085 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2334086 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2334086 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2334086 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2334086 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:56.139Z
[junit4] 2> 2334091 INFO (zkConnectionManagerCallback-9648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2334092 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2335589 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2335589 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2335590 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@984c0d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2335594 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2335598 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@130547[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2335599 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2335601 INFO (zkConnectionManagerCallback-9655-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2335606 INFO (zkConnectionManagerCallback-9657-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2335609 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2335611 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:36857_solr as DOWN
[junit4] 2> 2335612 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2335612 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36857_solr
[junit4] 2> 2335613 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9591-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9634-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335613 INFO (zkCallback-9641-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335614 INFO (zkCallback-9656-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2335632 INFO (zkConnectionManagerCallback-9664-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2335634 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2335634 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2335639 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2335667 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36857.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2335683 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36857.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2335683 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36857.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2335684 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node1/.
[junit4] 2> 2335742 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: https://127.0.0.1:40163/solr. Proxy: https://127.0.0.1:36857/solr
[junit4] 2> 2335742 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25836766
[junit4] 2> 2335742 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43549_solr
[junit4] 2> 2335742 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 2335742 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:43549_solr as DOWN
[junit4] 2> 2335745 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2335745 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1612f58: rootName = solr_43549, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@d8c382
[junit4] 2> 2335751 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2335751 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ed7565: rootName = solr_43549, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d352e8
[junit4] 2> 2335755 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2335755 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fa3d3: rootName = solr_43549, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2a43fc
[junit4] 2> 2335756 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2335760 INFO (closeThreadPool-9668-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72328756302118926-127.0.0.1:43549_solr-n_0000000002) closing
[junit4] 2> 2335760 INFO (OverseerStateUpdate-72328756302118926-127.0.0.1:43549_solr-n_0000000002) [n:127.0.0.1:43549_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43549_solr
[junit4] 2> 2335762 INFO (OverseerAutoScalingTriggerThread-72328756302118926-127.0.0.1:43549_solr-n_0000000002) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2335767 INFO (closeThreadPool-9668-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72328756302118926-127.0.0.1:43549_solr-n_0000000002) closing
[junit4] 2> 2335767 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335768 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.Overseer Overseer (id=72328756302118926-127.0.0.1:43549_solr-n_0000000002) closing
[junit4] 2> 2335769 INFO (zkCallback-9607-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335770 INFO (zkCallback-9634-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335770 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335770 INFO (zkCallback-9641-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335774 INFO (zkCallback-9663-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335774 INFO (zkCallback-9656-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2335774 INFO (zkCallback-9607-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34933_solr
[junit4] 2> 2335775 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ebb7d0{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2335775 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a0e836{/solr,null,UNAVAILABLE}
[junit4] 2> 2335775 INFO (zkCallback-9607-thread-2) [n:127.0.0.1:34933_solr ] o.a.s.c.Overseer Overseer (id=72328756302118929-127.0.0.1:34933_solr-n_0000000003) starting
[junit4] 2> 2335775 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2335775 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2335776 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2335777 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2335777 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 2335777 INFO (OverseerStateUpdate-72328756302118929-127.0.0.1:34933_solr-n_0000000003) [n:127.0.0.1:34933_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34933_solr
[junit4] 2> 2335802 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2335802 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2335802 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2335805 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22e085{/solr,null,AVAILABLE}
[junit4] 2> 2335805 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1b0d65d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6423f5{SSL,[ssl, http/1.1]}{127.0.0.1:38759}
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.s.Server Started @2335906ms
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34539}
[junit4] 2> 2335806 ERROR (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2335806 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2335807 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-06T15:03:57.859Z
[junit4] 2> 2335810 INFO (zkConnectionManagerCallback-9670-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2335810 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2339054 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2339057 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2339066 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@a65878[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2339079 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2339098 WARN (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@19041c6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2339100 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36111/solr
[junit4] 2> 2339105 INFO (zkConnectionManagerCallback-9677-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2339121 INFO (zkConnectionManagerCallback-9679-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2339125 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2339127 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:34539_solr as DOWN
[junit4] 2> 2339128 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2339128 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34539_solr
[junit4] 2> 2339129 INFO (zkCallback-9607-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9663-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9656-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9620-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9641-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9614-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339129 INFO (zkCallback-9634-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339138 INFO (zkCallback-9678-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2339174 INFO (zkConnectionManagerCallback-9686-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2339175 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2339176 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36111/solr ready
[junit4] 2> 2339185 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2339207 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34539.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2339223 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34539.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2339223 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34539.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ac0645
[junit4] 2> 2339224 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001/tempDir-001/node4/.
[junit4] 2> 2339305 INFO (TEST-LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection-seed#[8D66CBF2891768D4]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: https://127.0.0.1:38759/solr. Proxy: https://127.0.0.1:34539/solr
[junit4] 2> 2339305 INFO (TE
[...truncated too long message...]
-Dtests.file.encoding=UTF-8
[junit4] ERROR 113s J0 | LeaderVoteWaitTimeoutTest.basicTest <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:32979/solr
[junit4] > at __randomizedtesting.SeedInfo.seed([8D66CBF2891768D4:7F92DC90CDB265E7]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:660)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1055)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:830)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:763)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
[junit4] > at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:155)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] > at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
[junit4] > at sun.security.ssl.InputRecord.read(InputRecord.java:503)
[junit4] > at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:983)
[junit4] > at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:940)
[junit4] > at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:548)
[junit4] > ... 49 more
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_8D66CBF2891768D4-001
[junit4] 2> Apr 06, 2019 3:06:21 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=1822, maxMBSortInHeap=5.905191365712265, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@54e15b), locale=id, timezone=Etc/GMT+12
[junit4] 2> NOTE: Linux 4.18.0-15-generic i386/Oracle Corporation 1.8.0_172 (32-bit)/cpus=8,threads=1,free=123784816,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestStressReorder, HdfsSyncSliceTest, TestRecovery, TestPullReplica, FieldAnalysisRequestHandlerTest, SchemaVersionSpecificBehaviorTest, IndexSchemaRuntimeFieldTest, PrimitiveFieldTypeTest, DistributedSpellCheckComponentTest, XmlUpdateRequestHandlerTest, TestSolrIndexConfig, TestSerializedLuceneMatchVersion, SuggesterTSTTest, TestReqParamsAPI, TolerantUpdateProcessorTest, DocValuesTest, SolrCmdDistributorTest, SparseHLLTest, TestManagedSchemaAPI, BigEndianAscendingWordSerializerTest, CopyFieldTest, AssignTest, AddReplicaTest, RankQueryTest, TestLegacyTerms, TermsComponentTest, RecoveryAfterSoftCommitTest, TestComponentsName, TestConfigReload, AtomicUpdateProcessorFactoryTest, TestQueryUtils, HdfsLockFactoryTest, TestSimTriggerIntegration, NodeAddedTriggerTest, TestDeleteCollectionOnDownNodes, TestUninvertingReader, ForceLeaderTest, TestDeprecatedFilters, SmileWriterTest, MetricsHistoryIntegrationTest, TestNamedUpdateProcessors, HttpPartitionWithTlogReplicasTest, TestRangeQuery, TestReloadAndDeleteDocs, SSLMigrationTest, TestSortableTextField, CollectionsAPISolrJTest, TestSimDistribStateManager, InfoHandlerTest, DistributedQueryComponentCustomSortTest, TestCoreDiscovery, PropertiesRequestHandlerTest, TestConfig, ChaosMonkeySafeLeaderTest, TestSimpleQParserPlugin, TestSubQueryTransformer, RAMDirectoryFactoryTest, SolrPluginUtilsTest, TestFoldingMultitermQuery, FullHLLTest, SuggestComponentTest, SampleTest, TestWithCollection, PKIAuthenticationIntegrationTest, FacetPivotSmallTest, ImplicitSnitchTest, TestFaceting, BasicZkTest, TestClusterStateMutator, ConnectionManagerTest, RangeFacetCloudTest, TestExportWriter, TestRawResponseWriter, TestRequestStatusCollectionAPI, DistributedDebugComponentTest, TestSystemCollAutoCreate, TestPerFieldSimilarityWithDefaultOverride, TestPostingsSolrHighlighter, SolrMetricManagerTest, TestCoreAdminApis, SolrRequestParserTest, PathHierarchyTokenizerFactoryTest, RandomizedTaggerTest, DistanceUnitsTest, PolyFieldTest, PeerSyncWithLeaderTest, ShowFileRequestHandlerTest, SimpleFacetsTest, TestFileDictionaryLookup, TestLegacyFieldCache, HDFSCollectionsAPITest, DistributedFacetPivotLargeTest, TestUtils, SimpleCollectionCreateDeleteTest, TestUpdate, SolrGangliaReporterTest, BlockDirectoryTest, TestSolrCloudWithKerberosAlt, CategoryRoutedAliasUpdateProcessorTest, TestConfigSetsAPIExclusivity, SortSpecParsingTest, HealthCheckHandlerTest, ScheduledTriggerIntegrationTest, CoreSorterTest, StatelessScriptUpdateProcessorFactoryTest, TestImplicitCoreProperties, SolrIndexSplitterTest, SpellCheckComponentTest, TestElisionMultitermQuery, HdfsRecoverLeaseTest, TestCrossCoreJoin, ShardRoutingTest, TestHighFrequencyDictionaryFactory, TestSimNodeAddedTrigger, TestMinHashQParser, HighlighterConfigTest, CurrencyRangeFacetCloudTest, TestStandardQParsers, HttpSolrCallGetCoreTest, TestInPlaceUpdatesStandalone, ScriptEngineTest, TestFieldCache, DocumentBuilderTest, TestPKIAuthenticationPlugin, TriggerCooldownIntegrationTest, TestCloudJSONFacetSKG, IgnoreCommitOptimizeUpdateProcessorFactoryTest, CdcrUpdateLogTest, FileBasedSpellCheckerTest, CloudMLTQParserTest, TestCloudSearcherWarming, TestDynamicFieldResource, TestReloadDeadlock, TestObjectReleaseTracker, CoreAdminOperationTest, TestNumericRangeQuery64, RemoteQueryErrorTest, TestSolrCLIRunExample, TestBackupRepositoryFactory, ByteBuffersDirectoryFactoryTest, CreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest]
[junit4] Completed [504/851 (1!)] on J0 in 151.48s, 2 tests, 1 error <<< FAILURES!
[...truncated 46519 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/23875/consoleText
[repro] Revision: ef7be67ba12c8f518591b1e6ec7c1d88e0834dc1
[repro] Ant options: "-Dargs=-client -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=LeaderVoteWaitTimeoutTest -Dtests.method=basicTest -Dtests.seed=8D66CBF2891768D4 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=id -Dtests.timezone=Etc/GMT+12 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] LeaderVoteWaitTimeoutTest
[repro] ant compile-test
[...truncated 3564 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseG1GC" -Dtests.seed=8D66CBF2891768D4 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=id -Dtests.timezone=Etc/GMT+12 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 75 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
[...truncated 43 lines...]