Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/2271/ Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection Error Message: IOException occured when talking to server at: http://127.0.0.1:44016/solr Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:44016/solr at __randomizedtesting.SeedInfo.seed([FA8910BB9CBBAE2:A7B48DB17B8B8EC8]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:664) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:256) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:245) 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.LBHttpSolrClient.request(LBHttpSolrClient.java:213) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1110) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817) 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.testMostInSyncReplicasCanWinElection(LeaderVoteWaitTimeoutTest.java:166) 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: org.apache.http.client.ClientProtocolException at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:187) 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:549) ... 50 more Caused by: org.apache.http.ProtocolException: The server failed to respond with a valid HTTP response at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:149) 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) ... 53 more Build Log: [...truncated 14217 lines...] [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/init-core-data-001 [junit4] 2> 1986044 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[FA8910BB9CBBAE2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=39 numCloses=39 [junit4] 2> 1986044 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[FA8910BB9CBBAE2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1986045 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[FA8910BB9CBBAE2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 1986045 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[FA8910BB9CBBAE2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1986047 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting basicTest [junit4] 2> 1986048 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001 [junit4] 2> 1986048 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1986048 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1986048 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1986148 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkTestServer start zk server on port:64939 [junit4] 2> 1986148 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64939 [junit4] 2> 1986148 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64939 [junit4] 2> 1986155 INFO (zkConnectionManagerCallback-11517-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986158 INFO (zkConnectionManagerCallback-11519-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986159 INFO (zkConnectionManagerCallback-11521-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986164 WARN (jetty-launcher-11522-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1986164 WARN (jetty-launcher-11522-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1986164 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1986164 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1986164 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1986164 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1986164 INFO (jetty-launcher-11522-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_191-b12 [junit4] 2> 1986164 INFO (jetty-launcher-11522-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_191-b12 [junit4] 2> 1986165 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1986165 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1986165 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1986165 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@65417e3b{/solr,null,AVAILABLE} [junit4] 2> 1986166 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1986166 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1986166 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1986166 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fb2e65c{/solr,null,AVAILABLE} [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@51894d20{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:52755} [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.e.j.s.Server Started @1986786ms [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52755} [junit4] 2> 1986168 ERROR (jetty-launcher-11522-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1986168 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:56.688Z [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f1b9eab{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37272} [junit4] 2> 1986170 WARN (jetty-launcher-11522-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.e.j.s.Server Started @1986788ms [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37272} [junit4] 2> 1986170 ERROR (jetty-launcher-11522-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1986170 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:56.690Z [junit4] 2> 1986171 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1986171 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1986171 INFO (jetty-launcher-11522-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_191-b12 [junit4] 2> 1986173 INFO (zkConnectionManagerCallback-11524-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986173 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1986174 INFO (zkConnectionManagerCallback-11526-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986174 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1986174 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1986174 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1986175 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1986175 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@20dab1e4{/solr,null,AVAILABLE} [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ded8efd{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56603} [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.e.j.s.Server Started @1986794ms [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56603} [junit4] 2> 1986176 ERROR (jetty-launcher-11522-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1986176 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:56.696Z [junit4] 2> 1986179 WARN (jetty-launcher-11522-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1986180 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1986180 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1986181 INFO (zkConnectionManagerCallback-11528-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986181 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1986183 INFO (jetty-launcher-11522-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_191-b12 [junit4] 2> 1986187 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1986187 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1986187 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1986188 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1542fb70{/solr,null,AVAILABLE} [junit4] 2> 1986188 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@56284b4c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56907} [junit4] 2> 1986188 INFO (jetty-launcher-11522-thread-4) [ ] o.e.j.s.Server Started @1986807ms [junit4] 2> 1986188 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56907} [junit4] 2> 1986189 ERROR (jetty-launcher-11522-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1986189 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1986189 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1986189 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1986189 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1986189 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:56.709Z [junit4] 2> 1986192 INFO (zkConnectionManagerCallback-11530-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1986218 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1987114 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1987122 WARN (jetty-launcher-11522-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7e60abf6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987157 WARN (jetty-launcher-11522-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7ca38f86[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987158 INFO (jetty-launcher-11522-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1987238 INFO (zkConnectionManagerCallback-11536-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987295 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1987297 WARN (jetty-launcher-11522-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4785bed6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987300 WARN (jetty-launcher-11522-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@36b7dbe3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987301 INFO (jetty-launcher-11522-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1987303 INFO (zkConnectionManagerCallback-11542-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987306 INFO (zkConnectionManagerCallback-11544-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987368 INFO (zkConnectionManagerCallback-11548-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987382 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37272_solr [junit4] 2> 1987383 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.Overseer Overseer (id=72148632740364297-127.0.0.1:37272_solr-n_0000000000) starting [junit4] 2> 1987396 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56603_solr [junit4] 2> 1987405 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1987407 WARN (jetty-launcher-11522-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1d4dc246[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987410 INFO (zkConnectionManagerCallback-11555-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987411 WARN (jetty-launcher-11522-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3d64b26a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987412 INFO (jetty-launcher-11522-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1987415 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1987416 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1987418 INFO (OverseerStateUpdate-72148632740364297-127.0.0.1:37272_solr-n_0000000000) [n:127.0.0.1:37272_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37272_solr [junit4] 2> 1987419 INFO (zkCallback-11547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1987419 INFO (zkCallback-11543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1987418 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37272_solr as DOWN [junit4] 2> 1987420 INFO (zkConnectionManagerCallback-11561-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987421 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1987421 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37272_solr [junit4] 2> 1987422 INFO (zkConnectionManagerCallback-11566-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987424 INFO (zkCallback-11554-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1987425 INFO (zkCallback-11543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1987425 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1987426 INFO (zkConnectionManagerCallback-11568-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987432 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1987436 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:52755_solr as DOWN [junit4] 2> 1987437 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1987437 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52755_solr [junit4] 2> 1987438 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1987449 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1987452 INFO (zkCallback-11547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 1987455 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1987459 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1987461 INFO (zkCallback-11565-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1987463 INFO (zkConnectionManagerCallback-11577-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987470 INFO (zkCallback-11554-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1987471 INFO (zkCallback-11543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1987472 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1987473 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1987473 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1987494 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37272.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987500 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56603.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987506 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37272.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987507 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37272.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987507 INFO (jetty-launcher-11522-thread-1) [n:127.0.0.1:37272_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node1/. [junit4] 2> 1987511 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1987512 WARN (jetty-launcher-11522-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@28835558[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987513 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56603.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987513 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56603.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987514 INFO (jetty-launcher-11522-thread-3) [n:127.0.0.1:56603_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node3/. [junit4] 2> 1987518 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52755.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987526 WARN (jetty-launcher-11522-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@58556bdc[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1987526 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52755.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987526 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52755.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987526 INFO (jetty-launcher-11522-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1987527 INFO (jetty-launcher-11522-thread-2) [n:127.0.0.1:52755_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node2/. [junit4] 2> 1987529 INFO (zkConnectionManagerCallback-11584-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987531 INFO (zkConnectionManagerCallback-11586-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987538 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1987547 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:56907_solr as DOWN [junit4] 2> 1987548 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1987548 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56907_solr [junit4] 2> 1987550 INFO (zkCallback-11543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987550 INFO (zkCallback-11565-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987550 INFO (zkCallback-11554-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987550 INFO (zkCallback-11576-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987550 INFO (zkCallback-11547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987550 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987551 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1987574 INFO (zkConnectionManagerCallback-11593-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987576 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1987598 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1987598 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1987616 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56907.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987622 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56907.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987622 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56907.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1987623 INFO (jetty-launcher-11522-thread-4) [n:127.0.0.1:56907_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node4/. [junit4] 2> 1987655 INFO (zkConnectionManagerCallback-11599-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987657 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1987658 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1987661 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=713158454 [junit4] 2> 1987662 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37272_solr [junit4] 2> 1987662 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN... [junit4] 2> 1987662 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37272_solr as DOWN [junit4] 2> 1987664 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1987664 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@362c64aa: rootName = solr_37272, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4f02472f [junit4] 2> 1987670 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1987670 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d5ea70e: rootName = solr_37272, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21916691 [junit4] 2> 1987673 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1987673 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50afa338: rootName = solr_37272, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@35a15c27 [junit4] 2> 1987674 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1987674 INFO (closeThreadPool-11602-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72148632740364297-127.0.0.1:37272_solr-n_0000000000) closing [junit4] 2> 1987676 INFO (OverseerStateUpdate-72148632740364297-127.0.0.1:37272_solr-n_0000000000) [n:127.0.0.1:37272_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37272_solr [junit4] 2> 1987676 INFO (OverseerAutoScalingTriggerThread-72148632740364297-127.0.0.1:37272_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1987678 INFO (closeThreadPool-11602-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72148632740364297-127.0.0.1:37272_solr-n_0000000000) closing [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@71b24a25[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@7f5a6054[Terminated, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1987680 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.Overseer Overseer (id=72148632740364297-127.0.0.1:37272_solr-n_0000000000) closing [junit4] 2> 1987681 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 0x10052cc99850009, likely client has closed socket [junit4] 2> 1987681 INFO (zkCallback-11565-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987681 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987682 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@f1b9eab{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 1987682 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 0x10052cc9985000b, likely client has closed socket [junit4] 2> 1987683 INFO (zkCallback-11576-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987684 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6fb2e65c{/solr,null,UNAVAILABLE} [junit4] 2> 1987684 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1987686 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1987686 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1987687 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1987687 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1987689 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1987689 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1987689 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79e8d408{/solr,null,AVAILABLE} [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5de8417b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43020} [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server Started @1988309ms [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62338} [junit4] 2> 1987690 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1987690 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:58.210Z [junit4] 2> 1987691 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987692 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987692 INFO (zkCallback-11547-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56603_solr [junit4] 2> 1987693 INFO (zkCallback-11547-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1987693 INFO (zkConnectionManagerCallback-11604-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1987694 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1987694 INFO (zkCallback-11547-thread-1) [n:127.0.0.1:56603_solr ] o.a.s.c.Overseer Overseer (id=72148632740364298-127.0.0.1:56603_solr-n_0000000001) starting [junit4] 2> 1987700 INFO (OverseerStateUpdate-72148632740364298-127.0.0.1:56603_solr-n_0000000001) [n:127.0.0.1:56603_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:56603_solr [junit4] 2> 1987700 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 0x10052cc99850014, likely client has closed socket [junit4] 2> 1987711 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988136 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1988137 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2e9935e2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1988140 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ae67a9b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1988141 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1988143 INFO (zkConnectionManagerCallback-11610-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988146 INFO (zkConnectionManagerCallback-11612-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988153 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1988158 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62338_solr as DOWN [junit4] 2> 1988159 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1988159 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62338_solr [junit4] 2> 1988161 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988161 INFO (zkCallback-11565-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988161 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988161 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988161 INFO (zkCallback-11547-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988161 INFO (zkCallback-11576-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988162 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988162 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988179 INFO (zkConnectionManagerCallback-11619-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988181 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1988183 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1988183 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1988206 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62338.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988217 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62338.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988217 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62338.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988218 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [n:127.0.0.1:62338_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node1/. [junit4] 2> 1988254 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:43020/solr. Proxy: http://127.0.0.1:62338/solr [junit4] 2> 1988254 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=909187742 [junit4] 2> 1988254 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:56603_solr [junit4] 2> 1988255 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN... [junit4] 2> 1988255 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:56603_solr as DOWN [junit4] 2> 1988257 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1988258 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1988258 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4df2ee21: rootName = solr_56603, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4419abd [junit4] 2> 1988264 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1988264 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1056d1bc: rootName = solr_56603, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21916691 [junit4] 2> 1988267 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1988267 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73ea379e: rootName = solr_56603, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@35a15c27 [junit4] 2> 1988267 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1988269 INFO (closeThreadPool-11623-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72148632740364298-127.0.0.1:56603_solr-n_0000000001) closing [junit4] 2> 1988271 INFO (OverseerStateUpdate-72148632740364298-127.0.0.1:56603_solr-n_0000000001) [n:127.0.0.1:56603_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56603_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@3c339cd9[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1988271 INFO (OverseerAutoScalingTriggerThread-72148632740364298-127.0.0.1:56603_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1988272 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988272 INFO (zkCallback-11565-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988272 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52755_solr [junit4] 2> 1988273 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988273 INFO (zkCallback-11567-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988273 INFO (zkCallback-11576-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988274 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988274 INFO (zkCallback-11567-thread-1) [n:127.0.0.1:52755_solr ] o.a.s.c.Overseer Overseer (id=72148632740364302-127.0.0.1:52755_solr-n_0000000002) starting [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6146644[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1988278 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.Overseer Overseer (id=72148632740364298-127.0.0.1:56603_solr-n_0000000001) closing [junit4] 2> 1988279 INFO (closeThreadPool-11623-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72148632740364298-127.0.0.1:56603_solr-n_0000000001) closing [junit4] 2> 1988280 INFO (zkCallback-11618-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988282 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988283 INFO (OverseerStateUpdate-72148632740364302-127.0.0.1:52755_solr-n_0000000002) [n:127.0.0.1:52755_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:52755_solr [junit4] 2> 1988288 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4ded8efd{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 1988293 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@20dab1e4{/solr,null,UNAVAILABLE} [junit4] 2> 1988294 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1988295 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1988296 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1988298 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1988298 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1988299 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1988299 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1988299 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17f8e9e6{/solr,null,AVAILABLE} [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@54a95a02{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:58663} [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server Started @1988919ms [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57590} [junit4] 2> 1988300 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1988300 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:58.820Z [junit4] 2> 1988303 INFO (zkConnectionManagerCallback-11625-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988303 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1988672 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1988673 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4e6c7a7f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1988680 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@721423e7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1988682 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1988684 INFO (zkConnectionManagerCallback-11631-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988687 INFO (zkConnectionManagerCallback-11633-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988700 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1988704 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:57590_solr as DOWN [junit4] 2> 1988710 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1988710 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57590_solr [junit4] 2> 1988711 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988712 INFO (zkCallback-11576-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988712 INFO (zkCallback-11618-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988712 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988714 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988715 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988717 INFO (zkCallback-11567-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988718 INFO (zkCallback-11632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1988741 INFO (zkConnectionManagerCallback-11640-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988743 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1988744 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1988744 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1988766 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57590.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988777 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57590.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988778 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57590.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1988779 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node3/. [junit4] 2> 1988858 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:58663/solr. Proxy: http://127.0.0.1:57590/solr [junit4] 2> 1988858 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1108834638 [junit4] 2> 1988858 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:52755_solr [junit4] 2> 1988859 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN... [junit4] 2> 1988859 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:52755_solr as DOWN [junit4] 2> 1988862 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1988862 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5cf1b6a0: rootName = solr_52755, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6dfd1391 [junit4] 2> 1988866 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1988866 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fbe6474: rootName = solr_52755, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21916691 [junit4] 2> 1988869 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1988869 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70ec5526: rootName = solr_52755, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@35a15c27 [junit4] 2> 1988869 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4363e13[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@2247e083[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1988871 INFO (closeThreadPool-11644-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72148632740364302-127.0.0.1:52755_solr-n_0000000002) closing [junit4] 2> 1988871 INFO (OverseerStateUpdate-72148632740364302-127.0.0.1:52755_solr-n_0000000002) [n:127.0.0.1:52755_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52755_solr [junit4] 2> 1988871 INFO (OverseerAutoScalingTriggerThread-72148632740364302-127.0.0.1:52755_solr-n_0000000002) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 1988876 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988876 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56907_solr [junit4] 2> 1988877 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988877 INFO (zkCallback-11618-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988879 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988879 INFO (closeThreadPool-11644-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72148632740364302-127.0.0.1:52755_solr-n_0000000002) closing [junit4] 2> 1988881 INFO (zkCallback-11639-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988880 INFO (zkCallback-11585-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988880 INFO (zkCallback-11585-thread-1) [n:127.0.0.1:56907_solr ] o.a.s.c.Overseer Overseer (id=72148632740364305-127.0.0.1:56907_solr-n_0000000003) starting [junit4] 2> 1988881 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.Overseer Overseer (id=72148632740364302-127.0.0.1:52755_solr-n_0000000002) closing [junit4] 2> 1988881 INFO (zkCallback-11632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1988885 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@51894d20{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 1988886 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@65417e3b{/solr,null,UNAVAILABLE} [junit4] 2> 1988887 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1988888 INFO (OverseerStateUpdate-72148632740364305-127.0.0.1:56907_solr-n_0000000003) [n:127.0.0.1:56907_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:56907_solr [junit4] 2> 1988890 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1988890 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1988890 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1988890 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1988891 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1988891 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1988891 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1988891 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f5956ac{/solr,null,AVAILABLE} [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d334f1c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:55831} [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server Started @1989511ms [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63159} [junit4] 2> 1988892 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1988892 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-23T09:06:59.412Z [junit4] 2> 1988897 INFO (zkConnectionManagerCallback-11646-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1988898 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1989848 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1989849 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4f852b27[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1989853 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3e9c2df8[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1989854 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64939/solr [junit4] 2> 1989856 INFO (zkConnectionManagerCallback-11652-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1989859 INFO (zkConnectionManagerCallback-11654-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1989883 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1989903 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:63159_solr as DOWN [junit4] 2> 1989908 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1989908 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63159_solr [junit4] 2> 1989912 INFO (zkCallback-11585-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11592-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11618-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989912 INFO (zkCallback-11639-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989913 INFO (zkCallback-11653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1989943 INFO (zkConnectionManagerCallback-11661-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1989964 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1989966 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64939/solr ready [junit4] 2> 1989966 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1989990 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63159.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1990001 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63159.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1990002 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63159.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@794442c8 [junit4] 2> 1990003 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_FA8910BB9CBBAE2-001/tempDir-001/node2/. [junit4] 2> 1990072 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:55831/solr. Proxy: http://127.0.0.1:63159/solr [junit4] 2> 1990073 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=852930247 [junit4] 2> 1990073 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:56907_solr [junit4] 2> 1990076 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN... [junit4] 2> 1990076 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:56907_solr as DOWN [junit4] 2> 1990079 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1990080 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c9241cb: rootName = solr_56907, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@31412b03 [junit4] 2> 1990086 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1990086 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@742aaf01: rootName = solr_56907, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@21916691 [junit4] 2> 1990090 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1990090 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d105037: rootName = solr_56907, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@35a15c27 [junit4] 2> 1990090 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1990091 INFO (closeThreadPool-11665-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72148632740364305-127.0.0.1:56907_solr-n_0000000003) closing [junit4] 2> 1990094 INFO (OverseerStateUpdate-72148632740364305-127.0.0.1:56907_solr-n_0000000003) [n:127.0.0.1:56907_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56907_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@195f0875[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1990097 INFO (OverseerAutoScalingTriggerThread-72148632740364305-127.0.0.1:56907_solr-n_0000000003) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@74a21355[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 1990102 INFO (zkCallback-11598-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990102 INFO (zkCallback-11611-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990102 INFO (zkCallback-11618-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990102 INFO (zkCallback-11639-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990103 INFO (zkCallback-11632-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990104 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.Overseer Overseer (id=72148632740364305-127.0.0.1:56907_solr-n_0000000003) closing [junit4] 2> 1990105 INFO (zkCallback-11611-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62338_solr [junit4] 2> 1990106 INFO (zkCallback-11653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990106 INFO (zkCallback-11611-thread-2) [n:127.0.0.1:62338_solr ] o.a.s.c.Overseer Overseer (id=72148632740364310-127.0.0.1:62338_solr-n_0000000004) starting [junit4] 2> 1990106 INFO (closeThreadPool-11665-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72148632740364305-127.0.0.1:56907_solr-n_0000000003) closing [junit4] 2> 1990109 INFO (zkCallback-11660-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3) [junit4] 2> 1990111 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@56284b4c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} [junit4] 2> 1990111 INFO (OverseerStateUpdate-72148632740364310-127.0.0.1:62338_solr-n_0000000004) [n:127.0.0.1:62338_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:62338_solr [junit4] 2> 1990114 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1542fb70{/solr,null,UNAVAILABLE} [junit4] 2> 1990115 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1990119 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 1990119 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1990119 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1990120 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 1990120 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1990120 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1990120 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1990120 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68c16af6{/solr,null,AVAILABLE} [junit4] 2> 1990121 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d85d74b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40018} [junit4] 2> 1990121 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.e.j.s.Server Started @1990741ms [junit4] 2> 1990121 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45714} [junit4] 2> 1990121 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1990121 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[FA8910BB9CBBAE2]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1990121 INFO (TEST-LeaderVoteWaitTimeoutTest.bas [...truncated too long message...] fo.class [copy] Copying 1 file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/classes/test common-solr.compile-test: compile-test: BUILD SUCCESSFUL Total time: 1 minute 20 seconds [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" -Dtests.seed=FA8910BB9CBBAE2 -Dtests.slow=true -Dtests.locale=mt-MT -Dtests.timezone=Africa/Bangui -Dtests.asserts=true -Dtests.file.encoding=UTF-8 Buildfile: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/build.xml -clover.disable: ivy-configure: [ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ :: [ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/top-level-ivy-settings.xml install-junit4-taskdef: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: resolve-groovy: [ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public [ivy:cachepath] :: resolution report :: resolve 32ms :: artifacts dl 1ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 1 | 0 | 0 | 0 || 1 | 0 | --------------------------------------------------------------------- -init-totals: -test: [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test [junit4:pickseed] Seed property 'tests.seed' already defined: FA8910BB9CBBAE2 [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/temp [junit4] <JUnit4> says ¡Hola! Master seed: FA8910BB9CBBAE2 [junit4] Executing 5 suites with 2 JVMs. [junit4] [junit4] Started J0 PID(16366@serv1-vm3). [junit4] Started J1 PID(16367@serv1-vm3). [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] OK 31.6s J0 | LeaderVoteWaitTimeoutTest.basicTest [junit4] OK 36.8s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection [junit4] Completed [1/5] on J0 in 73.26s, 2 tests [junit4] [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] OK 38.1s J1 | LeaderVoteWaitTimeoutTest.basicTest [junit4] OK 34.5s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection [junit4] Completed [2/5] on J1 in 77.20s, 2 tests [junit4] [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] OK 18.4s J0 | LeaderVoteWaitTimeoutTest.basicTest [junit4] OK 27.1s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection [junit4] Completed [3/5] on J0 in 45.80s, 2 tests [junit4] [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] OK 16.2s J1 | LeaderVoteWaitTimeoutTest.basicTest [junit4] OK 27.8s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection [junit4] Completed [4/5] on J1 in 44.80s, 2 tests [junit4] [junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [junit4] OK 14.8s J0 | LeaderVoteWaitTimeoutTest.basicTest [junit4] OK 26.8s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection [junit4] Completed [5/5] on J0 in 41.93s, 2 tests [junit4] [junit4] JVM J0: 0.73 .. 162.90 = 162.18s [junit4] JVM J1: 0.73 .. 124.17 = 123.44s [junit4] Execution time total: 2 minutes 43 seconds [junit4] Tests summary: 5 suites, 10 tests [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp]) [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp]) [echo] 5 slowest tests: [junit4:tophints] 178.61s | org.apache.solr.cloud.api.collections.ShardSplitTest [junit4:tophints] 172.34s | org.apache.solr.search.facet.TestJsonFacets [junit4:tophints] 117.70s | org.apache.solr.update.SoftAutoCommitTest [junit4:tophints] 112.43s | org.apache.solr.handler.TestReplicationHandler [junit4:tophints] 86.57s | org.apache.solr.cloud.CollectionsAPISolrJTest -check-totals: test-nocompile: BUILD SUCCESSFUL Total time: 2 minutes 47 seconds [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest [repro] Exiting with code 0 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:957) at hudson.FilePath.act(FilePath.java:1072) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1074) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error? [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=893f5cda574715201f412c79069c06b79b6027ca, workspace=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris [WARNINGS] Computing warning deltas based on reference build #2270 Recording test results Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
