You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/03/17 16:25:24 UTC
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1739 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1739/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Collection not found: basicTest
Stack Trace:
org.apache.solr.common.SolrException: Collection not found: basicTest
at __randomizedtesting.SeedInfo.seed([C59A625780853227:376E7535C4203F14]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:852)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:818)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
Error Message:
Error from server at http://127.0.0.1:37857/solr: ADDREPLICA failed to create replica
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:37857/solr: ADDREPLICA failed to create replica
at __randomizedtesting.SeedInfo.seed([C59A625780853227:6D867EED42C5060D]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1105)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:885)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:818)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection(LeaderVoteWaitTimeoutTest.java:136)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 1845 lines...]
[junit4] JVM J1: stdout was not empty, see: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build/core/test/temp/junit4-J1-20180317_144336_9362566900863480248353.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] codec: SimpleText, pf: RAMOnly, dvf: Direct
[junit4] <<< JVM J1: EOF ----
[...truncated 11391 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_C59A625780853227-001/init-core-data-001
[junit4] 2> 1598499 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1598500 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1598502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-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> 1598502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1598502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] 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_C59A625780853227-001/tempDir-001
[junit4] 2> 1598502 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1598503 INFO (Thread-3601) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1598503 INFO (Thread-3601) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1598506 ERROR (Thread-3601) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1598604 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:60733
[junit4] 2> 1598611 INFO (zkConnectionManagerCallback-7400-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598625 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1598625 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1598625 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1598627 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1598627 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1598627 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1598630 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1598630 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1598630 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1598630 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1598631 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@799b46b7{/solr,null,AVAILABLE}
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2404ea5e{HTTP/1.1,[http/1.1]}{127.0.0.1:57131}
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.e.j.s.Server Started @1601837ms
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57131}
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1598632 ERROR (jetty-launcher-7397-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:01.222Z
[junit4] 2> 1598632 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79a5562e{/solr,null,AVAILABLE}
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@423240e9{HTTP/1.1,[http/1.1]}{127.0.0.1:44282}
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@54a24664{/solr,null,AVAILABLE}
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.e.j.s.Server Started @1601838ms
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44282}
[junit4] 2> 1598633 ERROR (jetty-launcher-7397-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1598633 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:01.223Z
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@419bb3b9{/solr,null,AVAILABLE}
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@246b797{HTTP/1.1,[http/1.1]}{127.0.0.1:34113}
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.e.j.s.Server Started @1601841ms
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34113}
[junit4] 2> 1598635 ERROR (jetty-launcher-7397-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1598635 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:01.226Z
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ce4ec26{HTTP/1.1,[http/1.1]}{127.0.0.1:65208}
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-1) [ ] o.e.j.s.Server Started @1601842ms
[junit4] 2> 1598636 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=65208}
[junit4] 2> 1598636 ERROR (jetty-launcher-7397-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1598637 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1598637 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1598637 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1598637 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:01.227Z
[junit4] 2> 1598641 INFO (zkConnectionManagerCallback-7404-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598641 INFO (zkConnectionManagerCallback-7408-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598652 INFO (zkConnectionManagerCallback-7402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598652 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1598641 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1598652 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1598653 INFO (zkConnectionManagerCallback-7406-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598660 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1598670 INFO (jetty-launcher-7397-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1598671 INFO (jetty-launcher-7397-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1598673 INFO (jetty-launcher-7397-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1598674 INFO (zkConnectionManagerCallback-7416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598676 INFO (zkConnectionManagerCallback-7413-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598678 INFO (zkConnectionManagerCallback-7422-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598679 INFO (jetty-launcher-7397-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1598681 INFO (zkConnectionManagerCallback-7426-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598682 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 0x10010879ac00006, likely client has closed socket
[junit4] 2> 1598684 INFO (zkConnectionManagerCallback-7428-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598685 INFO (zkConnectionManagerCallback-7420-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598685 INFO (zkConnectionManagerCallback-7430-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598687 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 0x10010879ac0000b, likely client has closed socket
[junit4] 2> 1598688 INFO (zkConnectionManagerCallback-7432-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598723 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1598724 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57131_solr
[junit4] 2> 1598725 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.c.Overseer Overseer (id=72075768640831495-127.0.0.1:57131_solr-n_0000000000) starting
[junit4] 2> 1598737 INFO (zkConnectionManagerCallback-7440-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1598740 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1598742 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57131_solr
[junit4] 2> 1598744 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1598745 INFO (OverseerStateUpdate-72075768640831495-127.0.0.1:57131_solr-n_0000000000) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1598876 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1598879 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1598883 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44282_solr
[junit4] 2> 1598885 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1598885 INFO (zkCallback-7427-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1598886 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1598890 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1598895 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1598899 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34113_solr
[junit4] 2> 1598900 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1598906 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1598907 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1598924 INFO (zkCallback-7427-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1598924 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1598926 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1598927 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1598930 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:65208_solr
[junit4] 2> 1598949 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1598949 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1598949 INFO (zkCallback-7427-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1598949 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1598949 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1599738 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44282.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1599835 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44282.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1599835 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44282.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1599836 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_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_C59A625780853227-001/tempDir-001/node4/.
[junit4] 2> 1599903 INFO (zkConnectionManagerCallback-7444-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1599915 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1599919 INFO (jetty-launcher-7397-thread-4) [n:127.0.0.1:44282_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1600070 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57131.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600084 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57131.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600084 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57131.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600085 INFO (jetty-launcher-7397-thread-3) [n:127.0.0.1:57131_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_C59A625780853227-001/tempDir-001/node3/.
[junit4] 2> 1600358 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65208.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600364 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65208.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600364 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_65208.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600365 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_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_C59A625780853227-001/tempDir-001/node1/.
[junit4] 2> 1600369 INFO (zkConnectionManagerCallback-7448-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600371 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1600372 INFO (jetty-launcher-7397-thread-1) [n:127.0.0.1:65208_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1600469 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34113.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600476 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34113.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600476 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34113.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1600477 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_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_C59A625780853227-001/tempDir-001/node2/.
[junit4] 2> 1600482 INFO (zkConnectionManagerCallback-7452-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600484 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1600486 INFO (jetty-launcher-7397-thread-2) [n:127.0.0.1:34113_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1600577 INFO (zkConnectionManagerCallback-7454-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600581 INFO (zkConnectionManagerCallback-7458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600582 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1600583 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1600589 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@423240e9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1600592 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1582393391
[junit4] 2> 1600592 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1600592 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33cb0293: rootName = solr_44282, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2d898ce1
[junit4] 2> 1600600 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1600600 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5efcb7f6: rootName = solr_44282, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7169961b
[junit4] 2> 1600605 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1600605 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d7e07fe: rootName = solr_44282, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75561cee
[junit4] 2> 1600606 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44282_solr
[junit4] 2> 1600607 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1600608 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600608 INFO (zkCallback-7427-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600608 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600608 INFO (zkCallback-7443-thread-1-processing-n:127.0.0.1:44282_solr) [n:127.0.0.1:44282_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600608 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600609 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600609 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600610 INFO (zkCallback-7447-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600611 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1600620 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@79a5562e{/solr,null,UNAVAILABLE}
[junit4] 2> 1600622 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1600624 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5ab7597b{/solr,null,AVAILABLE}
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45e05055{HTTP/1.1,[http/1.1]}{127.0.0.1:41708}
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server Started @1603831ms
[junit4] 2> 1600625 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64957}
[junit4] 2> 1600626 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1600626 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1600626 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1600626 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1600626 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:03.216Z
[junit4] 2> 1600627 INFO (zkConnectionManagerCallback-7460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600628 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1600632 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1600645 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1600646 INFO (zkConnectionManagerCallback-7464-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600649 INFO (zkConnectionManagerCallback-7466-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1600666 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1600670 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1600677 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64957_solr
[junit4] 2> 1600678 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600679 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600679 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600679 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600679 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600679 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600680 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1600680 INFO (zkCallback-7447-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601665 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64957.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1601674 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64957.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1601674 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64957.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1601675 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_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_C59A625780853227-001/tempDir-001/node4/.
[junit4] 2> 1601681 INFO (zkConnectionManagerCallback-7471-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1601683 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1601685 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:64957_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1601729 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:41708/solr. Proxy: http://127.0.0.1:64957/solr
[junit4] 2> 1601730 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2404ea5e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1601732 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=146740531
[junit4] 2> 1601733 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1601733 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7bf502e5: rootName = solr_57131, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2f5594d
[junit4] 2> 1601738 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1601738 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@510fd8da: rootName = solr_57131, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7169961b
[junit4] 2> 1601742 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1601742 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60dbb9b1: rootName = solr_57131, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75561cee
[junit4] 2> 1601745 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57131_solr
[junit4] 2> 1601747 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (zkCallback-7439-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (zkCallback-7447-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601747 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1601747 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1601748 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601748 INFO (zkCallback-7421-thread-1-processing-n:127.0.0.1:57131_solr) [n:127.0.0.1:57131_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601748 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1601748 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.Overseer Overseer (id=72075768640831495-127.0.0.1:57131_solr-n_0000000000) closing
[junit4] 2> 1601749 INFO (OverseerStateUpdate-72075768640831495-127.0.0.1:57131_solr-n_0000000000) [n:127.0.0.1:57131_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57131_solr
[junit4] 2> 1601751 WARN (OverseerAutoScalingTriggerThread-72075768640831495-127.0.0.1:57131_solr-n_0000000000) [n:127.0.0.1:57131_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1601769 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34113_solr
[junit4] 2> 1601769 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@799b46b7{/solr,null,UNAVAILABLE}
[junit4] 2> 1601772 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1601775 INFO (zkCallback-7429-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.Overseer Overseer (id=72075768640831498-127.0.0.1:34113_solr-n_0000000002) starting
[junit4] 2> 1601776 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1601776 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1601776 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1601777 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1601777 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@db51174{/solr,null,AVAILABLE}
[junit4] 2> 1601777 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5fa310e1{HTTP/1.1,[http/1.1]}{127.0.0.1:34909}
[junit4] 2> 1601777 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server Started @1604983ms
[junit4] 2> 1601777 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37857}
[junit4] 2> 1601777 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1601778 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1601778 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1601778 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1601778 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:04.368Z
[junit4] 2> 1601786 INFO (zkConnectionManagerCallback-7473-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1601787 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1601803 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1601805 INFO (zkConnectionManagerCallback-7477-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1601808 INFO (zkConnectionManagerCallback-7479-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1601818 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1601821 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1601841 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37857_solr
[junit4] 2> 1601872 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601873 INFO (zkCallback-7447-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601874 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601876 INFO (zkCallback-7429-thread-2-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601877 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601878 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601878 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1601879 INFO (zkCallback-7478-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602745 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37857.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1602751 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37857.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1602751 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37857.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1602759 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_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_C59A625780853227-001/tempDir-001/node3/.
[junit4] 2> 1602762 INFO (zkConnectionManagerCallback-7484-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602765 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1602767 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:37857_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1602805 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:34909/solr. Proxy: http://127.0.0.1:37857/solr
[junit4] 2> 1602806 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@ce4ec26{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1602808 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=584001163
[junit4] 2> 1602808 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1602808 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76e5a137: rootName = solr_65208, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@763a05db
[junit4] 2> 1602812 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1602812 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f2005df: rootName = solr_65208, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7169961b
[junit4] 2> 1602822 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1602822 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1894e7e2: rootName = solr_65208, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75561cee
[junit4] 2> 1602824 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:65208_solr
[junit4] 2> 1602826 INFO (zkCallback-7431-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602826 INFO (zkCallback-7429-thread-2-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602826 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602827 INFO (zkCallback-7447-thread-1-processing-n:127.0.0.1:65208_solr) [n:127.0.0.1:65208_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602827 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602827 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602828 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602828 INFO (zkCallback-7478-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602827 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1602828 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1602828 INFO (zkCallback-7483-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1602831 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@54a24664{/solr,null,UNAVAILABLE}
[junit4] 2> 1602834 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1602836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1602836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1602836 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1602837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1602837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@353f152c{/solr,null,AVAILABLE}
[junit4] 2> 1602837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@66826d93{HTTP/1.1,[http/1.1]}{127.0.0.1:62722}
[junit4] 2> 1602837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server Started @1606044ms
[junit4] 2> 1602837 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38314}
[junit4] 2> 1602837 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1602838 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1602838 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1602838 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1602838 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:05.428Z
[junit4] 2> 1602839 INFO (zkConnectionManagerCallback-7486-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602840 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1602847 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1602849 INFO (zkConnectionManagerCallback-7490-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602852 INFO (zkConnectionManagerCallback-7492-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1602861 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1602864 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1602867 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38314_solr
[junit4] 2> 1602869 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602869 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602869 INFO (zkCallback-7429-thread-2-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602869 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602869 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602870 INFO (zkCallback-7478-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602870 INFO (zkCallback-7483-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1602870 INFO (zkCallback-7491-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603230 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38314.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603244 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38314.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603244 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38314.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603245 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_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_C59A625780853227-001/tempDir-001/node1/.
[junit4] 2> 1603248 INFO (zkConnectionManagerCallback-7497-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603250 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1603251 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38314_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1603294 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:62722/solr. Proxy: http://127.0.0.1:38314/solr
[junit4] 2> 1603295 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@246b797{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1603297 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1972603231
[junit4] 2> 1603298 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1603298 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5a3759aa: rootName = solr_34113, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5a7c442a
[junit4] 2> 1603302 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1603302 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1814d15d: rootName = solr_34113, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7169961b
[junit4] 2> 1603304 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1603304 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fff49f9: rootName = solr_34113, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@75561cee
[junit4] 2> 1603307 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34113_solr
[junit4] 2> 1603308 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1603308 INFO (zkCallback-7429-thread-2-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603308 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603308 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603308 INFO (zkCallback-7451-thread-1-processing-n:127.0.0.1:34113_solr) [n:127.0.0.1:34113_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603308 INFO (zkCallback-7478-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603309 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1603309 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603309 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.Overseer Overseer (id=72075768640831498-127.0.0.1:34113_solr-n_0000000002) closing
[junit4] 2> 1603310 INFO (OverseerStateUpdate-72075768640831498-127.0.0.1:34113_solr-n_0000000002) [n:127.0.0.1:34113_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34113_solr
[junit4] 2> 1603315 INFO (zkCallback-7491-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603315 INFO (zkCallback-7496-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603315 WARN (OverseerAutoScalingTriggerThread-72075768640831498-127.0.0.1:34113_solr-n_0000000002) [n:127.0.0.1:34113_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1603315 INFO (zkCallback-7483-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1603330 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64957_solr
[junit4] 2> 1603331 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@419bb3b9{/solr,null,UNAVAILABLE}
[junit4] 2> 1603333 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1603333 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.Overseer Overseer (id=72075768640831509-127.0.0.1:64957_solr-n_0000000004) starting
[junit4] 2> 1603336 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1603336 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1603336 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1603336 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@602a656a{/solr,null,AVAILABLE}
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@95a414a{HTTP/1.1,[http/1.1]}{127.0.0.1:33875}
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.e.j.s.Server Started @1606544ms
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38930}
[junit4] 2> 1603337 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1603337 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-17T15:27:05.927Z
[junit4] 2> 1603339 INFO (zkConnectionManagerCallback-7499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603339 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1603350 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60733/solr
[junit4] 2> 1603351 INFO (zkConnectionManagerCallback-7503-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603353 INFO (zkConnectionManagerCallback-7505-thread-1-processing-n:127.0.0.1:38930_solr) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603362 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1603365 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1603368 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38930_solr
[junit4] 2> 1603370 INFO (zkCallback-7465-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603370 INFO (zkCallback-7470-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603370 INFO (zkCallback-7457-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603370 INFO (zkCallback-7483-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603370 INFO (zkCallback-7496-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603371 INFO (zkCallback-7478-thread-1-processing-n:127.0.0.1:37857_solr) [n:127.0.0.1:37857_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603371 INFO (zkCallback-7491-thread-1-processing-n:127.0.0.1:38314_solr) [n:127.0.0.1:38314_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603371 INFO (zkCallback-7504-thread-1-processing-n:127.0.0.1:38930_solr) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1603906 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38930.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603912 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38930.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603912 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38930.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@31392c7f
[junit4] 2> 1603913 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_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_C59A625780853227-001/tempDir-001/node2/.
[junit4] 2> 1603915 INFO (zkConnectionManagerCallback-7510-thread-1-processing-n:127.0.0.1:38930_solr) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1603917 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1603918 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [n:127.0.0.1:38930_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60733/solr ready
[junit4] 2> 1603951 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:33875/solr. Proxy: http://127.0.0.1:38930/solr
[junit4] 2> 1604230 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[C59A625780853227]) [ ] o.a.s.SolrTestCaseJ4 ###Starting basicTest
[junit4] 2> 1604231 INFO (SocketProxy-Acceptor-37857) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=49890,localport=37857], receiveBufferSize:130880
[junit4] 2> 1604234 INFO (SocketProxy-Acceptor-37857) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=34909,localport=49104], receiveBufferSize=139264
[junit4] 2> 1604239 INFO (qtp1719561234-21561) [n:127.0.0.1:37857_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&name=basicTest&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:64957_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1604245 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection basicTest
[junit4] 2> 1604247 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/basicTest.AUTOCREATED/managed-schema
[junit4] 2> 1604250 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/basicTest.AUTOCREATED/protwords.txt
[junit4] 2> 1604252 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/basicTest.AUTOCREATED/solrconfig.xml
[junit4] 2> 1604254 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/basicTest.AUTOCREATED/synonyms.txt
[junit4] 2> 1604256 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/basicTest.AUTOCREATED/stopwords.txt
[junit4] 2> 1604259 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/basicTest.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1604262 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1604264 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1604267 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/basicTest.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1604269 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/basicTest.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1604272 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1604274 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1604277 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1604280 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1604283 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/basicTest.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1604285 INFO (OverseerThreadFactory-5636-thread-1-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/basicTest.AUTOCREATED/lang/stopw
[...truncated too long message...]
ead-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1637848 INFO (zkCallback-7470-thread-2-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1637848 INFO (jetty-closer-7398-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@db51174{/solr,null,UNAVAILABLE}
[junit4] 2> 1637849 INFO (zkCallback-7504-thread-1-processing-n:127.0.0.1:38930_solr) [n:127.0.0.1:38930_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1637850 INFO (jetty-closer-7398-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72075768640831509-127.0.0.1:64957_solr-n_0000000004) closing
[junit4] 2> 1637851 INFO (OverseerStateUpdate-72075768640831509-127.0.0.1:64957_solr-n_0000000004) [n:127.0.0.1:64957_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64957_solr
[junit4] 2> 1637852 INFO (zkCallback-7465-thread-2-processing-n:127.0.0.1:64957_solr) [n:127.0.0.1:64957_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1637852 INFO (jetty-closer-7398-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@602a656a{/solr,null,UNAVAILABLE}
[junit4] 2> 1637854 INFO (jetty-closer-7398-thread-6) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1637854 INFO (jetty-closer-7398-thread-4) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1637856 WARN (OverseerAutoScalingTriggerThread-72075768640831509-127.0.0.1:64957_solr-n_0000000004) [n:127.0.0.1:64957_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1637871 INFO (jetty-closer-7398-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5ab7597b{/solr,null,UNAVAILABLE}
[junit4] 2> 1637874 INFO (jetty-closer-7398-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1637876 ERROR (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1637876 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[C59A625780853227]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60733 60733
[junit4] 2> 1640260 INFO (Thread-3601) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60733 60733
[junit4] 2> 1640265 WARN (Thread-3601) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/aliases.json
[junit4] 2> 17 /solr/clusterprops.json
[junit4] 2> 8 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/basicTest/state.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72075768640831495-127.0.0.1:57131_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/72075768640831509-127.0.0.1:64957_solr-n_0000000004
[junit4] 2> 2 /solr/overseer_elect/election/72075768640831513-127.0.0.1:37857_solr-n_0000000005
[junit4] 2> 2 /solr/overseer_elect/election/72075768640831498-127.0.0.1:34113_solr-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 17 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_C59A625780853227-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=61, maxMBSortInHeap=7.58456603882582, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7738d78d), locale=fr-CA, timezone=Pacific/Niue
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=1,free=52833080,total=344698880
[junit4] 2> NOTE: All tests run in this JVM: [ShowFileRequestHandlerTest, RulesTest, TestConfigReload, SimplePostToolTest, NodeMutatorTest, TestStressLiveNodes, TestCloudDeleteByQuery, TestManagedSchemaAPI, TestSolrCoreProperties, TestExactStatsCache, LeaderFailoverAfterPartitionTest, SolrCmdDistributorTest, SearchHandlerTest, TestPolicyCloud, RegexBoostProcessorTest, AnalyticsMergeStrategyTest, ClusterStateTest, TestScoreJoinQPScore, TestSolrCloudWithKerberosAlt, HttpTriggerListenerTest, BadIndexSchemaTest, AssignTest, DistribDocExpirationUpdateProcessorTest, BufferStoreTest, TestSubQueryTransformerCrossCore, TestRTGBase, TestSlowCompositeReaderWrapper, ChangedSchemaMergeTest, TestImpersonationWithHadoopAuth, FieldAnalysisRequestHandlerTest, ExternalFileFieldSortTest, TestRestManager, TestHdfsUpdateLog, TestNRTOpen, TriLevelCompositeIdRoutingTest, TestMissingGroups, TestReplicaProperties, MinimalSchemaTest, TestSha256AuthenticationProvider, MoveReplicaTest, TestSearchPerf, TermVectorComponentTest, TestNamedUpdateProcessors, TestLuceneMatchVersion, DocValuesMultiTest, SpellCheckComponentTest, SpellPossibilityIteratorTest, TestQuerySenderListener, TestCustomDocTransformer, DistributedQueueTest, SolrJmxReporterTest, CollectionsAPIDistributedZkTest, TestLegacyNumericRangeQueryBuilder, TestMaxTokenLenTokenizer, SecurityConfHandlerTest, TestDynamicLoading, DeleteLastCustomShardedReplicaTest, TestCrossCoreJoin, TestJsonFacetRefinement, PrimUtilsTest, TestDistribIDF, TestSolrConfigHandler, ZkControllerTest, ConnectionReuseTest, MetricsHandlerTest, TestSimpleTrackingShardHandler, TestSkipOverseerOperations, TestRandomFaceting, FileBasedSpellCheckerTest, InfixSuggestersTest, UUIDFieldTest, DocExpirationUpdateProcessorFactoryTest, CloudMLTQParserTest, TestConfigSetsAPIZkFailure, TestCloudJSONFacetJoinDomain, TestTolerantUpdateProcessorCloud, DistributedSuggestComponentTest, TestSchemaVersionResource, BadCopyFieldTest, DeleteReplicaTest, AddBlockUpdateTest, TestSSLRandomization, TestTrieFacet, TestLegacyField, DocValuesNotIndexedTest, CoreAdminHandlerTest, ConcurrentCreateRoutedAliasTest, TestInPlaceUpdatesStandalone, TestCollectionAPI, ZkSolrClientTest, TestSmileRequest, HealthCheckHandlerTest, TestSQLHandler, TestPointFields, QueryParsingTest, StatsReloadRaceTest, TestUtilizeNode, SolrCloudReportersTest, TestNestedDocsSort, HdfsLockFactoryTest, TestLegacyFieldCache, BadComponentTest, TestDistributedMap, AutoAddReplicasIntegrationTest, TestReqParamsAPI, TestQueryWrapperFilter, TestFieldResource, RestartWhileUpdatingTest, TestManagedResource, SolrCoreCheckLockOnStartupTest, TestUpdate, TestSolrQueryParser, TestStressCloudBlindAtomicUpdates, ClassificationUpdateProcessorTest, TestSchemalessBufferedUpdates, TestExtendedDismaxParser, ScheduledMaintenanceTriggerTest, CdcrRequestHandlerTest, IndexSchemaTest, EchoParamsTest, HdfsChaosMonkeySafeLeaderTest, TestCustomSort, TestFieldTypeResource, TestLeaderElectionZkExpiry, TestCoreAdminApis, CdcrBidirectionalTest, RankQueryTest, TestFreeTextSuggestions, TestSchemaResource, TestHdfsCloudBackupRestore, DistributedFacetPivotLongTailTest, TestClusterStateProvider, TestRetrieveFieldsOptimizer, CollectionStateFormat2Test, SpellCheckCollatorTest, TolerantUpdateProcessorTest, TestShardHandlerFactory, TestCoreDiscovery, TestFuzzyAnalyzedSuggestions, DebugComponentTest, TestSolr4Spatial, TestInfoStreamLogging, SparseHLLTest, TestMultiValuedNumericRangeQuery, TestReversedWildcardFilterFactory, ExecutePlanActionTest, LoggingHandlerTest, HDFSCollectionsAPITest, PeerSyncTest, NotRequiredUniqueKeyTest, TestDeleteCollectionOnDownNodes, TestDocumentBuilder, TestSolrConfigHandlerCloud, AtomicUpdateProcessorFactoryTest, RequestLoggingTest, LeaderVoteWaitTimeoutTest]
[junit4] Completed [359/785 (1!)] on J1 in 41.81s, 2 tests, 2 errors <<< FAILURES!
[...truncated 51017 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1739/consoleText
[repro] Revision: be8dca3c7bc064bc42662cb3fa6eb7439ffc7fdb
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=LeaderVoteWaitTimeoutTest -Dtests.method=basicTest -Dtests.seed=C59A625780853227 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=LeaderVoteWaitTimeoutTest -Dtests.method=testMostInSyncReplicasCanWinElection -Dtests.seed=C59A625780853227 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] LeaderVoteWaitTimeoutTest
[repro] ant compile-test
[...truncated 3313 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=C59A625780853227 -Dtests.slow=true -Dtests.locale=fr-CA -Dtests.timezone=Pacific/Niue -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 73 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
[...truncated 70 lines...]
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1740 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1740/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads
Error Message:
MockDirectoryWrapper: cannot close: there are still 12 open files: {_5.cfs=1, _2.cfs=1, _0.fdx=1, _e.cfs=1, _e.fdt=1, _a.cfs=1, _0.fdt=1, _0.tvx=1, _0.tvd=1, _3.cfs=1, _4.cfs=1, _e.cfe=1}
Stack Trace:
java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 12 open files: {_5.cfs=1, _2.cfs=1, _0.fdx=1, _e.cfs=1, _e.fdt=1, _a.cfs=1, _0.fdt=1, _0.tvx=1, _0.tvd=1, _3.cfs=1, _4.cfs=1, _e.cfe=1}
at __randomizedtesting.SeedInfo.seed([9CE603A3D3FCF102:474EB95AB9FF4289]:0)
at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:841)
at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:331)
at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads(TestIndexWriterWithThreads.java:492)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: unclosed IndexInput: _e.fdt
at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:732)
at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:776)
at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:119)
at org.apache.lucene.codecs.lucene50.Lucene50CompoundFormat.write(Lucene50CompoundFormat.java:93)
at org.apache.lucene.index.IndexWriter.createCompoundFile(IndexWriter.java:5083)
at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4579)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4154)
at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
FAILED: org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
Error Message:
MockDirectoryWrapper: cannot close: there are still 58 open files: {_5.pos=1, _h_Lucene70_0.dvd=1, _d.doc=1, _5.doc=1, _f.pos=1, _h_Lucene70_0.dvm=1, _5.fdt=1, _7.pos=1, _c.tim=1, _d.tvd=1, _e_Lucene70_0.dvd=1, _c.nvd=1, _7.doc=1, _f.fdt=1, _6.tvd=1, _f.tvd=1, _e.tim=1, _4_Lucene70_0.dvd=1, _e.nvd=1, _7.tim=1, _d.fdt=1, _7.nvd=1, _f.doc=1, _4.tvd=1, _6_Lucene70_0.dvd=1, _4.doc=1, _5.nvd=1, _7.fdt=1, _5.tim=1, _d.pos=1, _c.doc=1, _e.pos=1, _4.pos=1, _6.fdt=1, _5_Lucene70_0.dvd=1, _c_Lucene70_0.dvd=1, _f_Lucene70_0.dvd=1, _d.nvd=1, _6.doc=1, _e.fdt=1, _4.fdt=1, _6.pos=1, _d.tim=1, _c.tvd=1, _f.tim=1, _d_Lucene70_0.dvd=1, _f.nvd=1, _c.fdt=1, _6.tim=1, _6.nvd=1, _4.tim=1, _7.tvd=1, _e.tvd=1, _4.nvd=1, _c.pos=1, _e.doc=1, _7_Lucene70_0.dvd=1, _5.tvd=1}
Stack Trace:
java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 58 open files: {_5.pos=1, _h_Lucene70_0.dvd=1, _d.doc=1, _5.doc=1, _f.pos=1, _h_Lucene70_0.dvm=1, _5.fdt=1, _7.pos=1, _c.tim=1, _d.tvd=1, _e_Lucene70_0.dvd=1, _c.nvd=1, _7.doc=1, _f.fdt=1, _6.tvd=1, _f.tvd=1, _e.tim=1, _4_Lucene70_0.dvd=1, _e.nvd=1, _7.tim=1, _d.fdt=1, _7.nvd=1, _f.doc=1, _4.tvd=1, _6_Lucene70_0.dvd=1, _4.doc=1, _5.nvd=1, _7.fdt=1, _5.tim=1, _d.pos=1, _c.doc=1, _e.pos=1, _4.pos=1, _6.fdt=1, _5_Lucene70_0.dvd=1, _c_Lucene70_0.dvd=1, _f_Lucene70_0.dvd=1, _d.nvd=1, _6.doc=1, _e.fdt=1, _4.fdt=1, _6.pos=1, _d.tim=1, _c.tvd=1, _f.tim=1, _d_Lucene70_0.dvd=1, _f.nvd=1, _c.fdt=1, _6.tim=1, _6.nvd=1, _4.tim=1, _7.tvd=1, _e.tvd=1, _4.nvd=1, _c.pos=1, _e.doc=1, _7_Lucene70_0.dvd=1, _5.tvd=1}
at __randomizedtesting.SeedInfo.seed([9CE603A3D3FCF102:FD4B0B2C0FD272BE]:0)
at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:841)
at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:331)
at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads(TestIndexWriterWithThreads.java:448)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: unclosed IndexInput: _d.pos
at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:732)
at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:776)
at org.apache.lucene.codecs.lucene50.Lucene50PostingsReader.<init>(Lucene50PostingsReader.java:92)
at org.apache.lucene.codecs.lucene50.Lucene50PostingsFormat.fieldsProducer(Lucene50PostingsFormat.java:443)
at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
at org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
at org.apache.lucene.index.ReadersAndUpdates.getReaderForMerge(ReadersAndUpdates.java:831)
at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4485)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4154)
at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
Build Log:
[...truncated 967 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterWithThreads -Dtests.method=testIOExceptionDuringAbortWithThreads -Dtests.seed=9CE603A3D3FCF102 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Pacific/Kosrae -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.44s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads <<<
[junit4] > Throwable #1: java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 58 open files: {_5.pos=1, _h_Lucene70_0.dvd=1, _d.doc=1, _5.doc=1, _f.pos=1, _h_Lucene70_0.dvm=1, _5.fdt=1, _7.pos=1, _c.tim=1, _d.tvd=1, _e_Lucene70_0.dvd=1, _c.nvd=1, _7.doc=1, _f.fdt=1, _6.tvd=1, _f.tvd=1, _e.tim=1, _4_Lucene70_0.dvd=1, _e.nvd=1, _7.tim=1, _d.fdt=1, _7.nvd=1, _f.doc=1, _4.tvd=1, _6_Lucene70_0.dvd=1, _4.doc=1, _5.nvd=1, _7.fdt=1, _5.tim=1, _d.pos=1, _c.doc=1, _e.pos=1, _4.pos=1, _6.fdt=1, _5_Lucene70_0.dvd=1, _c_Lucene70_0.dvd=1, _f_Lucene70_0.dvd=1, _d.nvd=1, _6.doc=1, _e.fdt=1, _4.fdt=1, _6.pos=1, _d.tim=1, _c.tvd=1, _f.tim=1, _d_Lucene70_0.dvd=1, _f.nvd=1, _c.fdt=1, _6.tim=1, _6.nvd=1, _4.tim=1, _7.tvd=1, _e.tvd=1, _4.nvd=1, _c.pos=1, _e.doc=1, _7_Lucene70_0.dvd=1, _5.tvd=1}
[junit4] > at __randomizedtesting.SeedInfo.seed([9CE603A3D3FCF102:FD4B0B2C0FD272BE]:0)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:841)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:331)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads(TestIndexWriterWithThreads.java:448)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.RuntimeException: unclosed IndexInput: _d.pos
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:732)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:776)
[junit4] > at org.apache.lucene.codecs.lucene50.Lucene50PostingsReader.<init>(Lucene50PostingsReader.java:92)
[junit4] > at org.apache.lucene.codecs.lucene50.Lucene50PostingsFormat.fieldsProducer(Lucene50PostingsFormat.java:443)
[junit4] > at org.apache.lucene.index.SegmentCoreReaders.<init>(SegmentCoreReaders.java:112)
[junit4] > at org.apache.lucene.index.SegmentReader.<init>(SegmentReader.java:78)
[junit4] > at org.apache.lucene.index.ReadersAndUpdates.getReader(ReadersAndUpdates.java:208)
[junit4] > at org.apache.lucene.index.ReadersAndUpdates.getReaderForMerge(ReadersAndUpdates.java:831)
[junit4] > at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4485)
[junit4] > at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4154)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] 1> org.apache.lucene.store.LockObtainFailedException: lock instance already obtained: (dir=RAMDirectory@23cc151e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@27f395bf, lockName=write.lock)
[junit4] 1> at org.apache.lucene.store.SingleInstanceLockFactory.obtainLock(SingleInstanceLockFactory.java:44)
[junit4] 1> at org.apache.lucene.store.BaseDirectory.obtainLock(BaseDirectory.java:45)
[junit4] 1> at org.apache.lucene.store.FilterDirectory.obtainLock(FilterDirectory.java:104)
[junit4] 1> at org.apache.lucene.store.MockDirectoryWrapper.obtainLock(MockDirectoryWrapper.java:1049)
[junit4] 1> at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:952)
[junit4] 1> at org.apache.lucene.index.TestIndexWriterWithThreads$DelayedIndexAndCloseRunnable.run(TestIndexWriterWithThreads.java:564)
[junit4] IGNOR/A 0.00s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] > Assumption #1: aborting test: timeout obtaining lock
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build/core/test/J0/temp/lucene.index.TestIndexWriterWithThreads_9CE603A3D3FCF102-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6145b2f2), locale=vi, timezone=Pacific/Kosrae
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=1,free=165102696,total=219660288
[junit4] 2> NOTE: All tests run in this JVM: [TestStringHelper, TestCodecUtil, TestIndexableField, TestIntsRef, TestNeverDelete, TestFilterDirectoryReader, TestMutableValues, TestTopFieldCollectorEarlyTermination, TestPrefixQuery, TestAllFilesCheckIndexHeader, TestFloatRange, TestExitableDirectoryReader, TestSpanNearQuery, TestIndexOptions, TestSpanCollection, TestRegExp, TestInfoStream, TestArrayUtil, TestReusableStringReader, TestMultiThreadTermVectors, TestAxiomaticF1LOG, TestGeoUtils, TestSpanExplanationsOfNonMatches, TestBooleanMinShouldMatch, TestNGramPhraseQuery, Test2BSortedDocValuesFixedSorted, TestDirectory, TestDirectPacked, TestSpans, TestSegmentReader, TestBKD, TestLongBitSet, TestOneMergeWrappingMergePolicy, TestIntroSorter, TestDocsAndPositions, TestSimpleExplanationsOfNonMatches, TestSynonymQuery, TestFieldsReader, TestSpanMultiTermQueryWrapper, TestIndexWriter, TestParallelReaderEmptyIndex, TestParallelCompositeReader, TestSpanExplanations, TestSpanSearchEquivalence, TestRAMDirectory, TestAxiomaticF3LOG, TestOmitPositions, TestManyFields, TestBlendedTermQuery, TestMathUtil, TestCachingCollector, TestDoubleValuesSource, Test2BPostingsBytes, TestMutablePointsReaderUtils, TestLucene60FieldInfoFormat, TestPrefixRandom, TestHugeRamFile, TestBM25Similarity, TestDocValues, TestCrashCausesCorruptIndex, TestBooleanQueryVisitSubscorers, TestCachingTokenFilter, TestIntBlockPool, TestDocIDMerger, TestControlledRealTimeReopenThread, TestCrash, TestReqExclBulkScorer, TestPackedTokenAttributeImpl, TestRegexpQuery, TestPolygon2D, TestCollectionUtil, Test2BBinaryDocValues, TestAxiomaticF2LOG, TestLucene50StoredFieldsFormatHighCompression, TestNoMergeScheduler, TestLockFactory, TestSort, TestBagOfPostings, TestReaderWrapperDVTypeCheck, TestSpanOrQuery, TestMultiLevelSkipList, TestLMDirichletSimilarity, TestPositiveScoresOnlyCollector, TestBooleanOr, Test2BNumericDocValues, TestComplexExplanations, TestScorerPerf, TestDuelingCodecs, TestUnicodeUtil, TestCharacterUtils, TestBooleanQuery, TestIndexWriterConfig, TestForceMergeForever, TestCompiledAutomaton, TermInSetQueryTest, TestNotDocIdSet, TestSegmentTermEnum, TestSortedSetSelector, TestMixedCodecs, TestTopDocsMerge, TestLucene70NormsFormat, TestTragicIndexWriterDeadlock, TestNot, TestNativeFSLockFactory, TestFilterDirectory, TestSnapshotDeletionPolicy, TestHighCompressionMode, TestNorms, TestIndexSearcher, TestSubScorerFreqs, TestConsistentFieldNumbers, TestSloppyPhraseQuery, TestSpanFirstQuery, TestTotalHitCountCollector, TestLiveFieldValues, TestMixedDocValuesUpdates, TestMmapDirectory, TestDoc, TestSingleInstanceLockFactory, TestIndexInput, TestAllFilesHaveChecksumFooter, TestUpgradeIndexMergePolicy, TestPerFieldDocValuesFormat, TestIndexWriterMerging, TestFSTs, TestIndexWriterWithThreads]
[junit4] Completed [221/486 (1!)] on J0 in 1.62s, 12 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 870 lines...]
[junit4] JVM J1: stdout was not empty, see: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build/core/test/temp/junit4-J1-20180318_015033_3942109068417031103354.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] codec: SimpleText, pf: BlockTreeOrds, dvf: Memory
[junit4] <<< JVM J1: EOF ----
[...truncated 62387 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1740/consoleText
[repro] Revision: c98ab259c503d5b5713ae238647cf58ad82b71ae
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=TestIndexWriterWithThreads -Dtests.method=testIOExceptionDuringAbortWithThreads -Dtests.seed=9CE603A3D3FCF102 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Pacific/Kosrae -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] lucene/core
[repro] TestIndexWriterWithThreads
[repro] ant compile-test
[...truncated 142 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestIndexWriterWithThreads" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=9CE603A3D3FCF102 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Pacific/Kosrae -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 95 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterWithThreads
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterWithThreads -Dtests.method=testIOExceptionDuringWriteSegmentWithThreads -Dtests.seed=9CE603A3D3FCF102 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=vi -Dtests.timezone=Pacific/Kosrae -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.12s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads <<<
[junit4] > Throwable #1: java.lang.RuntimeException: MockDirectoryWrapper: cannot close: there are still 12 open files: {_5.cfs=1, _2.cfs=1, _0.fdx=1, _e.cfs=1, _e.fdt=1, _a.cfs=1, _0.fdt=1, _0.tvx=1, _0.tvd=1, _3.cfs=1, _4.cfs=1, _e.cfe=1}
[junit4] > at __randomizedtesting.SeedInfo.seed([9CE603A3D3FCF102:474EB95AB9FF4289]:0)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.close(MockDirectoryWrapper.java:841)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads._testMultipleThreadsFailure(TestIndexWriterWithThreads.java:331)
[junit4] > at org.apache.lucene.index.TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreads(TestIndexWriterWithThreads.java:492)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.lang.RuntimeException: unclosed IndexInput: _e.fdt
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:732)
[junit4] > at org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:776)
[junit4] > at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
[junit4] > at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
[junit4] > at org.apache.lucene.store.FilterDirectory.openInput(FilterDirectory.java:99)
[junit4] > at org.apache.lucene.store.Directory.openChecksumInput(Directory.java:119)
[junit4] > at org.apache.lucene.codecs.lucene50.Lucene50CompoundFormat.write(Lucene50CompoundFormat.java:93)
[junit4] > at org.apache.lucene.index.IndexWriter.createCompoundFile(IndexWriter.java:5083)
[junit4] > at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4579)
[junit4] > at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4154)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:624)
[junit4] > at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:661)
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentOnlyOnce
[junit4] OK 0.03s J0 | TestIndexWriterWithThreads.testImmediateDiskFullWithThreads
[junit4] OK 0.22s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreads
[junit4] OK 0.10s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortWithThreadsOnlyOnce
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbort
[junit4] OK 0.34s J0 | TestIndexWriterWithThreads.testRollbackAndCommitWithThreads
[junit4] OK 0.56s J0 | TestIndexWriterWithThreads.testCloseWithThreads
[junit4] OK 0.04s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegmentWithThreadsOnlyOnce
[junit4] 1> org.apache.lucene.store.LockObtainFailedException: lock instance already obtained: (dir=RAMDirectory@35e8a2b9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31d720dc, lockName=write.lock)
[junit4] 1> at org.apache.lucene.store.SingleInstanceLockFactory.obtainLock(SingleInstanceLockFactory.java:44)
[junit4] 1> at org.apache.lucene.store.BaseDirectory.obtainLock(BaseDirectory.java:45)
[junit4] 1> at org.apache.lucene.store.FilterDirectory.obtainLock(FilterDirectory.java:104)
[junit4] 1> at org.apache.lucene.store.MockDirectoryWrapper.obtainLock(MockDirectoryWrapper.java:1049)
[junit4] 1> at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:952)
[junit4] 1> at org.apache.lucene.index.TestIndexWriterWithThreads$DelayedIndexAndCloseRunnable.run(TestIndexWriterWithThreads.java:564)
[junit4] IGNOR/A 0.00s J0 | TestIndexWriterWithThreads.testOpenTwoIndexWritersOnDifferentThreads
[junit4] > Assumption #1: aborting test: timeout obtaining lock
[junit4] OK 0.01s J0 | TestIndexWriterWithThreads.testIOExceptionDuringAbortOnlyOnce
[junit4] OK 0.02s J0 | TestIndexWriterWithThreads.testIOExceptionDuringWriteSegment
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/build/core/test/J0/temp/lucene.index.TestIndexWriterWithThreads_9CE603A3D3FCF102-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2c91b4a6), locale=vi, timezone=Pacific/Kosrae
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_162 (64-bit)/cpus=3,threads=1,free=33207632,total=97320960
[junit4] 2> NOTE: All tests run in this JVM: [TestIndexWriterWithThreads, TestIndexWriterWithThreads, TestIndexWriterWithThreads]
[junit4] Completed [5/5 (1!)] on J0 in 1.53s, 12 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1561: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/lucene/common-build.xml:1085: There were test failures: 5 suites, 60 tests, 1 error, 4 ignored (4 assumptions) [seed: 9CE603A3D3FCF102]
Total time: 10 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed: org.apache.lucene.index.TestIndexWriterWithThreads
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
mv: cannot access solr/build
+ true
+ 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
[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=c98ab259c503d5b5713ae238647cf58ad82b71ae, workspace=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris
[WARNINGS] Computing warning deltas based on reference build #1739
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