Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/640/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
Error Message:
Timeout waiting for new leader null Live Nodes: [127.0.0.1:56821_solr,
127.0.0.1:56831_solr, 127.0.0.1:56839_solr] Last available state:
DocCollection(collection1//collections/collection1/state.json/13)={
"pullReplicas":"0", "replicationFactor":"3", "shards":{"shard1":{
"range":"80000000-7fffffff", "state":"active", "replicas":{
"core_node62":{ "core":"collection1_shard1_replica_n61",
"base_url":"http://127.0.0.1:56815/solr";,
"node_name":"127.0.0.1:56815_solr", "state":"down",
"type":"NRT", "force_set_state":"false"}, "core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:56821/solr";,
"node_name":"127.0.0.1:56821_solr", "state":"active",
"type":"NRT", "force_set_state":"false"}, "core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:56831/solr";,
"node_name":"127.0.0.1:56831_solr", "state":"active",
"type":"NRT", "force_set_state":"false"}}}},
"router":{"name":"compositeId"}, "maxShardsPerNode":"1",
"autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timeout waiting for new leader
null
Live Nodes: [127.0.0.1:56821_solr, 127.0.0.1:56831_solr, 127.0.0.1:56839_solr]
Last available state:
DocCollection(collection1//collections/collection1/state.json/13)={
"pullReplicas":"0",
"replicationFactor":"3",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{
"core_node62":{
"core":"collection1_shard1_replica_n61",
"base_url":"http://127.0.0.1:56815/solr";,
"node_name":"127.0.0.1:56815_solr",
"state":"down",
"type":"NRT",
"force_set_state":"false"},
"core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:56821/solr";,
"node_name":"127.0.0.1:56821_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"},
"core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:56831/solr";,
"node_name":"127.0.0.1:56831_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([86D8570A7971909:A07199CA65D72D23]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:278)
at
org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection(LeaderVoteWaitTimeoutTest.java:187)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
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
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.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 13119 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/init-core-data-001
[junit4] 2> 750582 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 750583 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 750583 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 750583 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001
[junit4] 2> 750583 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 750584 INFO (Thread-1371) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 750584 INFO (Thread-1371) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 750587 ERROR (Thread-1371) [ ] 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> 750692 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:56791
[junit4] 2> 750703 INFO (zkConnectionManagerCallback-2297-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750710 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 750710 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 750711 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 750712 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 750712 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 750712 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 750713 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 750715 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@219dd52{/solr,null,AVAILABLE}
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@385a38d0{/solr,null,AVAILABLE}
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@32e71729{HTTP/1.1,[http/1.1]}{127.0.0.1:56793}
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-4) [ ]
o.e.j.s.Server Started @750799ms
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2e6eeddb{HTTP/1.1,[http/1.1]}{127.0.0.1:56794}
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-3) [ ]
o.e.j.s.Server Started @750800ms
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56793}
[junit4] 2> 750716 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56794}
[junit4] 2> 750717 ERROR (jetty-launcher-2294-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 750717 ERROR (jetty-launcher-2294-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3d055072{/solr,null,AVAILABLE}
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6c52bbb6{HTTP/1.1,[http/1.1]}{127.0.0.1:56795}
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.e.j.s.Server Started @750800ms
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:56.389243Z
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56795}
[junit4] 2> 750717 ERROR (jetty-launcher-2294-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:56.389243Z
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 750721 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 750717 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 750721 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 750722 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 750722 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 750722 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:56.394071Z
[junit4] 2> 750723 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@304288bf{/solr,null,AVAILABLE}
[junit4] 2> 750724 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5ac54e5c{HTTP/1.1,[http/1.1]}{127.0.0.1:56798}
[junit4] 2> 750724 INFO (zkConnectionManagerCallback-2303-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750724 INFO (jetty-launcher-2294-thread-2) [ ]
o.e.j.s.Server Started @750807ms
[junit4] 2> 750724 INFO (zkConnectionManagerCallback-2299-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750724 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56798}
[junit4] 2> 750725 ERROR (jetty-launcher-2294-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 750725 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 750725 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 750725 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 750725 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 750725 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:56.397936Z
[junit4] 2> 750726 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 750726 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 750726 INFO (zkConnectionManagerCallback-2301-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750727 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 750736 INFO (zkConnectionManagerCallback-2305-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750739 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 750763 INFO (jetty-launcher-2294-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 750763 INFO (jetty-launcher-2294-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 750763 INFO (jetty-launcher-2294-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 750764 INFO (jetty-launcher-2294-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 750765 INFO (zkConnectionManagerCallback-2314-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750765 INFO (zkConnectionManagerCallback-2318-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750766 INFO (zkConnectionManagerCallback-2321-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750766 INFO (zkConnectionManagerCallback-2316-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750770 INFO (zkConnectionManagerCallback-2325-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750770 INFO (zkConnectionManagerCallback-2324-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750771 INFO (zkConnectionManagerCallback-2327-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 750771 INFO (zkConnectionManagerCallback-2329-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751036 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 751038 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:56794_solr
[junit4] 2> 751039 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 751042 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.Overseer Overseer
(id=72117919934185484-127.0.0.1:56794_solr-n_0000000000) starting
[junit4] 2> 751043 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56795_solr
[junit4] 2> 751052 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 751052 INFO (zkCallback-2322-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 751052 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 751052 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 751072 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 751079 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 751079 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56793_solr
[junit4] 2> 751081 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 751087 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 751088 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 751091 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 751093 INFO (zkCallback-2322-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 751100 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 751101 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56798_solr
[junit4] 2> 751105 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 751105 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 751107 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 751107 INFO (zkCallback-2322-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 751117 INFO (zkConnectionManagerCallback-2342-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751122 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 751127 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 751137 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 751137 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56794_solr
[junit4] 2> 751139 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751139 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751139 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751140 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751140 INFO (zkCallback-2322-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751311 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56793.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751335 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56793.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751335 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56793.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751351 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/.
[junit4] 2> 751354 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56798.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751356 INFO (zkConnectionManagerCallback-2347-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751366 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 751366 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56795.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751368 INFO (jetty-launcher-2294-thread-4)
[n:127.0.0.1:56793_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 751395 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56798.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751396 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56798.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751397 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node2/.
[junit4] 2> 751402 INFO (zkConnectionManagerCallback-2352-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751405 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 751408 INFO (jetty-launcher-2294-thread-2)
[n:127.0.0.1:56798_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 751422 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56795.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751424 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56795.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751426 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node1/.
[junit4] 2> 751431 INFO (zkConnectionManagerCallback-2357-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751434 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 751437 INFO (jetty-launcher-2294-thread-1)
[n:127.0.0.1:56795_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 751496 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56794.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751529 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56794.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751529 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56794.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751551 INFO (jetty-launcher-2294-thread-3)
[n:127.0.0.1:56794_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node3/.
[junit4] 2> 751619 INFO (zkConnectionManagerCallback-2359-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751629 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
0x10036ddb79e0011, likely client has closed socket
[junit4] 2> 751631 INFO (zkConnectionManagerCallback-2364-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751633 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 751635 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56791/solr ready
[junit4] 2> 751643 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@32e71729{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 751643 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=799725869
[junit4] 2> 751643 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 751643 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2f64abb4: rootName =
solr_56793, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5c4fc93b
[junit4] 2> 751652 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 751652 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6ff75d3c: rootName =
solr_56793, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4671c938
[junit4] 2> 751658 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 751658 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7ff0c36b: rootName =
solr_56793, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5a19ef85
[junit4] 2> 751660 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:56793_solr
[junit4] 2> 751663 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2322-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2346-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2351-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751663 INFO (zkCallback-2356-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751664 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751664 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751664 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 751669 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@219dd52{/solr,null,UNAVAILABLE}
[junit4] 2> 751669 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 751671 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 751672 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 751672 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 751672 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 751673 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@75ad51b7{/solr,null,AVAILABLE}
[junit4] 2> 751673 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@237c38b7{HTTP/1.1,[http/1.1]}{127.0.0.1:56816}
[junit4] 2> 751673 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server Started @751756ms
[junit4] 2> 751673 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56815}
[junit4] 2> 751673 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 751675 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 751675 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 751675 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 751675 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 751675 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:57.347668Z
[junit4] 2> 751678 INFO (zkConnectionManagerCallback-2366-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751679 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 751684 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
0x10036ddb79e0013, likely client has closed socket
[junit4] 2> 751689 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 751691 INFO (zkConnectionManagerCallback-2370-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751700 INFO (zkConnectionManagerCallback-2372-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751718 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 751724 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 751728 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 751728 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56815_solr
[junit4] 2> 751732 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751732 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751732 INFO (zkCallback-2351-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751732 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751733 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751733 INFO (zkCallback-2356-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751733 INFO (zkCallback-2371-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751733 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 751846 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56815.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751855 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56815.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751855 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56815.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 751856 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/.
[junit4] 2> 751860 INFO (zkConnectionManagerCallback-2379-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751863 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 751865 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56815_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 751930 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:56816/solr. Proxy: http://127.0.0.1:56815/solr
[junit4] 2> 751931 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@5ac54e5c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 751932 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=781393766
[junit4] 2> 751932 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 751932 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@139bf62a: rootName =
solr_56798, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@63d90478
[junit4] 2> 751949 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 751950 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@68d342ba: rootName =
solr_56798, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4671c938
[junit4] 2> 751956 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 751956 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1b5fa0a8: rootName =
solr_56798, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5a19ef85
[junit4] 2> 751958 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:56798_solr
[junit4] 2> 751960 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 751966 INFO (zkCallback-2356-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2351-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2371-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751966 INFO (zkCallback-2326-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751967 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 751983 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@304288bf{/solr,null,UNAVAILABLE}
[junit4] 2> 751983 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 751985 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1825404a{/solr,null,AVAILABLE}
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@41bfaf44{HTTP/1.1,[http/1.1]}{127.0.0.1:56822}
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server Started @752071ms
[junit4] 2> 751987 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56821}
[junit4] 2> 751988 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 751988 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 751989 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 751989 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 751989 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 751989 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:57.661144Z
[junit4] 2> 751991 INFO (zkConnectionManagerCallback-2381-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 751992 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 752004 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 752010 INFO (zkConnectionManagerCallback-2385-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752014 INFO (zkConnectionManagerCallback-2387-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752031 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 752035 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 752039 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 752039 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56821_solr
[junit4] 2> 752044 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752044 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2356-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752045 INFO (zkCallback-2386-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752597 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56821.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752613 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56821.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752613 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56821.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752616 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node2/.
[junit4] 2> 752621 INFO (zkConnectionManagerCallback-2394-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752624 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 752627 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56821_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 752684 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:56822/solr. Proxy: http://127.0.0.1:56821/solr
[junit4] 2> 752694 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@6c52bbb6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 752694 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=926963406
[junit4] 2> 752695 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 752695 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4764b1b8: rootName =
solr_56795, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@534436dc
[junit4] 2> 752704 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 752704 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2f5c9bbf: rootName =
solr_56795, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4671c938
[junit4] 2> 752711 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 752711 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2b9408d7: rootName =
solr_56795, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5a19ef85
[junit4] 2> 752714 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:56795_solr
[junit4] 2> 752718 INFO (zkCallback-2386-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2323-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752718 INFO (zkCallback-2356-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752719 INFO (zkCallback-2393-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 752720 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 752721 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 752726 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3d055072{/solr,null,UNAVAILABLE}
[junit4] 2> 752727 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 752731 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 752734 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 752734 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 752734 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 752734 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7a013e46{/solr,null,AVAILABLE}
[junit4] 2> 752736 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6bbbc676{HTTP/1.1,[http/1.1]}{127.0.0.1:56832}
[junit4] 2> 752736 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server Started @752819ms
[junit4] 2> 752736 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56831}
[junit4] 2> 752736 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 752736 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 752737 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 752737 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 752737 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 752737 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:58.409185Z
[junit4] 2> 752739 INFO (zkConnectionManagerCallback-2396-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752740 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 752769 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 752771 INFO (zkConnectionManagerCallback-2400-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752777 INFO (zkConnectionManagerCallback-2402-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 752800 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 752809 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 752816 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 752816 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56831_solr
[junit4] 2> 752836 INFO (zkCallback-2386-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752837 INFO (zkCallback-2393-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752838 INFO (zkCallback-2401-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 752980 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56831.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752992 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56831.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752993 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56831.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 752997 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node1/.
[junit4] 2> 753001 INFO (zkConnectionManagerCallback-2409-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 753003 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 753005 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56831_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 753080 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:56832/solr. Proxy: http://127.0.0.1:56831/solr
[junit4] 2> 753082 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2e6eeddb{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 753083 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1783954681
[junit4] 2> 753083 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 753083 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1eb063a4: rootName =
solr_56794, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5389c3df
[junit4] 2> 753090 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 753090 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@447c893b: rootName =
solr_56794, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4671c938
[junit4] 2> 753095 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 753095 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@733da12e: rootName =
solr_56794, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5a19ef85
[junit4] 2> 753098 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:56794_solr
[junit4] 2> 753101 INFO (zkCallback-2386-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2341-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2401-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2328-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2393-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753101 INFO (zkCallback-2408-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 753102 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 753104 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.Overseer Overseer
(id=72117919934185484-127.0.0.1:56794_solr-n_0000000000) closing
[junit4] 2> 753105 INFO
(OverseerStateUpdate-72117919934185484-127.0.0.1:56794_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56794_solr
[junit4] 2> 753105 WARN
(OverseerAutoScalingTriggerThread-72117919934185484-127.0.0.1:56794_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 753110 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56815_solr
[junit4] 2> 753110 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@385a38d0{/solr,null,UNAVAILABLE}
[junit4] 2> 753111 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 753112 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git:
daa59876e6f384329b122929e70a80934569428c; jvm 9+181
[junit4] 2> 753112 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72117919934185493-127.0.0.1:56815_solr-n_0000000004) starting
[junit4] 2> 753114 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 753114 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 753114 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 753119 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4472f142{/solr,null,AVAILABLE}
[junit4] 2> 753121 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7f2e6e02{HTTP/1.1,[http/1.1]}{127.0.0.1:56840}
[junit4] 2> 753121 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.e.j.s.Server Started @753204ms
[junit4] 2> 753121 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56839}
[junit4] 2> 753122 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 753125 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 753129 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.4.0
[junit4] 2> 753129 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 753129 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 753129 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T21:59:58.801831Z
[junit4] 2> 753133 INFO (zkConnectionManagerCallback-2411-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 753134 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 753142 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56791/solr
[junit4] 2> 753144 INFO (zkConnectionManagerCallback-2415-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 753147 INFO (zkConnectionManagerCallback-2417-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 753161 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 753165 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 753173 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 753173 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56839_solr
[junit4] 2> 753175 INFO (zkCallback-2401-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2408-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2363-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2386-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2371-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2393-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2378-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753176 INFO (zkCallback-2416-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 753305 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56839.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 753319 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56839.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 753319 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56839.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7f24a877
[junit4] 2> 753321 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node3/.
[junit4] 2> 753326 INFO (zkConnectionManagerCallback-2424-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 753328 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 753331 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker)
[n:127.0.0.1:56839_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:56791/solr ready
[junit4] 2> 753379 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:56840/solr. Proxy: http://127.0.0.1:56839/solr
[junit4] 2> 753417 INFO
(TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[86D8570A7971909]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting basicTest
[junit4] 2> 753418 INFO (SocketProxy-Acceptor-56815) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=56845,localport=56815], receiveBufferSize:408300
[junit4] 2> 753419 INFO (SocketProxy-Acceptor-56815) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=56816,localport=56846], receiveBufferSize=408300
[junit4] 2> 753421 INFO (qtp1564930162-8098) [n:127.0.0.1:56815_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:56815_solr&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 753425 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection basicTest
[junit4] 2> 753426 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to
/configs/basicTest.AUTOCREATED/managed-schema
[junit4] 2> 753429 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to
/configs/basicTest.AUTOCREATED/protwords.txt
[junit4] 2> 753435 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to
/configs/basicTest.AUTOCREATED/solrconfig.xml
[junit4] 2> 753439 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to
/configs/basicTest.AUTOCREATED/synonyms.txt
[junit4] 2> 753442 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to
/configs/basicTest.AUTOCREATED/stopwords.txt
[junit4] 2> 753446 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753450 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753454 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753457 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753460 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753464 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753471 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753474 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753478 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753482 INFO (OverseerThreadFactory-3444-thread-1) [ ]
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> 753485 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_pt.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 753489 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_hu.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 753494 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_el.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 753497 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ru.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 753500 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_tr.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 753504 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ar.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 753508 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/userdict_ja.txt to
/configs/basicTest.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 753510 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_eu.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 753513 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_it.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 753516 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_cz.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 753520 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stoptags_ja.txt to
/configs/basicTest.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 753524 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/contractions_fr.txt to
/configs/basicTest.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 753531 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_de.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 753534 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_fa.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 753537 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/contractions_ga.txt to
/configs/basicTest.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 753539 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ca.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 753543 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_nl.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 753553 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_bg.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 753559 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_en.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 753562 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_es.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 753567 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_lv.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 753571 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ro.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 753573 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_th.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 753590 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_fr.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 753599 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_sv.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 753604 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_fi.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 753609 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/hyphenations_ga.txt to
/configs/basicTest.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 753613 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_gl.txt to
/configs/basicTest.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 753616 INFO (OverseerThreadFactory-3444-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to
/configs/basicTest.AUTOCREATED/params.json
[junit4] 2> 753749 INFO
(OverseerStateUpdate-72117919934185493-127.0.0.1:56815_solr-n_0000000004) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"basicTest",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"basicTest_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56815/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 753963 INFO (SocketProxy-Acceptor-56815) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=56847,localport=56815], receiveBufferSize:408300
[junit4] 2> 753964 INFO (SocketProxy-Acceptor-56815) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=56816,localport=56848], receiveBufferSize=408300
[junit4] 2> 753964 INFO (qtp1564930162-8094) [n:127.0.0.1:56815_solr
x:basicTest_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=basicTest.AUTOCREATED&newCollection=true&name=basicTest_shard1_replica_n1&action=CREATE&numShards=1&collection=basicTest&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 754998 INFO (qtp1564930162-8094) [n:127.0.0.1:56815_solr
c:basicTest s:shard1 r:core_node2 x:basicTest_shard1_replica_n1]
o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 754998 INFO (qtp1564930162-8094) [n:127.0.0.1:56815_solr
c:basicTest s:shard1 r:core_node2 x:basicTest_shard1_replica_n1]
o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 754999 WARN (qtp1564930162-8094) [n:127.0.0.1:56815_solr
c:basicTest s:shard1 r:core_node2 x:basicTest_shard1_replica_n1]
o.a.s.c.SolrConfig Couldn't add files from
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/basicTest_shard1_replica_n1/../../../../contrib/extraction/lib
filtered by .*\.jar to classpath:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/basicTest_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 754999 WARN (qtp1564930162-8094) [n:127.0.0.1:56815_solr
c:basicTest s:shard1 r:core_node2 x:basicTest_shard1_replica_n1]
o.a.s.c.SolrConfig Couldn't add files from
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/basicTest_shard1_replica_n1/../../../../dist
filtered by solr-cell-\d.*\.jar to classpath:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001/tempDir-001/node4/basicTest_shard1_replica_n1/../../../../dist
[junit4] 2> 754999 WARN (qtp1564930162-8094) [n:127.0.0.1:56815_solr
c:basicTest s:shard1 r:core_node2 x:basicTest_shard1_replica_n1]
o.a.s.c.SolrConfig Couldn't add files from
/Users/jenkins/workspace/Lucene-Solr-7.
[...truncated too long message...]
sing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3368fc09:
rootName = solr_56831, domain = solr.core.collection1.shard1.replica_n65,
service url = null, agent id = null] for registry
solr.core.collection1.shard1.replica_n65 /
com.codahale.metrics.MetricRegistry@2ce8d447
[junit4] 2> 769508 INFO (zkCallback-2401-thread-1)
[n:127.0.0.1:56831_solr c:collection1 s:shard1 r:core_node66
x:collection1_shard1_replica_n65] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=211d8d27
[junit4] 2> 774865 ERROR
(recoveryExecutor-2383-thread-1-processing-n:127.0.0.1:56821_solr
x:collection1_shard1_replica_n63 c:collection1 s:shard1 r:core_node64)
[n:127.0.0.1:56821_solr c:collection1 s:shard1 r:core_node64
x:collection1_shard1_replica_n63] o.a.s.c.RecoveryStrategy SolrCore not found -
cannot recover:collection1_shard1_replica_n63
[junit4] 2> 774866 WARN
(updateExecutor-2382-thread-2-processing-n:127.0.0.1:56821_solr
x:collection1_shard1_replica_n63 c:collection1 s:shard1 r:core_node64)
[n:127.0.0.1:56821_solr c:collection1 s:shard1 r:core_node64
x:collection1_shard1_replica_n63] o.a.s.u.DefaultSolrCoreState Skipping
recovery because Solr is shutdown
[junit4] 2> 774866 INFO (jetty-closer-2295-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72117919934185497-127.0.0.1:56821_solr-n_0000000005) closing
[junit4] 2> 774866 INFO
(OverseerStateUpdate-72117919934185497-127.0.0.1:56821_solr-n_0000000005) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56821_solr
[junit4] 2> 774868 WARN
(OverseerAutoScalingTriggerThread-72117919934185497-127.0.0.1:56821_solr-n_0000000005)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 774871 INFO (jetty-closer-2295-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1825404a{/solr,null,UNAVAILABLE}
[junit4] 2> 774871 INFO (jetty-closer-2295-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 774872 INFO (zkCallback-2401-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56831_solr
[junit4] 2> 777080 ERROR
(recoveryExecutor-2398-thread-1-processing-n:127.0.0.1:56831_solr
x:collection1_shard1_replica_n65 c:collection1 s:shard1 r:core_node66)
[n:127.0.0.1:56831_solr c:collection1 s:shard1 r:core_node66
x:collection1_shard1_replica_n65] o.a.s.c.RecoveryStrategy SolrCore not found -
cannot recover:collection1_shard1_replica_n65
[junit4] 2> 777083 INFO (jetty-closer-2295-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7a013e46{/solr,null,UNAVAILABLE}
[junit4] 2> 777083 INFO (jetty-closer-2295-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 777085 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-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> 777085 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[86D8570A7971909]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:56791 56791
[junit4] 2> 777186 INFO (Thread-1371) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:56791 56791
[junit4] 2> 777363 WARN (Thread-1371) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/collections/collection1/terms/shard1
[junit4] 2> 19 /solr/clusterprops.json
[junit4] 2> 19 /solr/aliases.json
[junit4] 2> 12 /solr/collections/basicTest/terms/shard1
[junit4] 2> 9 /solr/security.json
[junit4] 2> 3
/solr/configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/configs/collection1.AUTOCREATED
[junit4] 2> 3
/solr/configs/basicTest.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/configs/basicTest.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/clusterstate.json
[junit4] 2> 18 /solr/collections/collection1/state.json
[junit4] 2> 4 /solr/collections/basicTest/state.json
[junit4] 2> 3
/solr/overseer_elect/election/72117919934185481-127.0.0.1:56795_solr-n_0000000001
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 2
/solr/overseer_elect/election/72117919934185484-127.0.0.1:56794_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/live_nodes
[junit4] 2> 19 /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:
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_86D8570A7971909-001
[junit4] 2> May 14, 2018 10:00:23 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {}, locale=es-ES,
timezone=Europe/Astrakhan
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=147491512,total=406323200
[junit4] 2> NOTE: All tests run in this JVM: [TestRemoteStreaming,
TestPerFieldSimilarityWithDefaultOverride, DistribCursorPagingTest,
SortSpecParsingTest, TestGroupingSearch, LeaderElectionTest,
SolrJmxReporterTest, AnalysisAfterCoreReloadTest, TestXIncludeConfig,
SolrCLIZkUtilsTest, TestSchemaSimilarityResource, SolrRequestParserTest,
ExitableDirectoryReaderTest, TestRawTransformer, TestRangeQuery,
ChaosMonkeySafeLeaderWithPullReplicasTest, NumberUtilsTest,
TestFastOutputStream, TestDistribDocBasedVersion, TestImplicitCoreProperties,
TestGraphTermsQParserPlugin, ClassificationUpdateProcessorFactoryTest,
BasicAuthStandaloneTest, TestJmxIntegration, SampleTest,
DirectSolrSpellCheckerTest, OverseerTaskQueueTest, CursorMarkTest,
TestSweetSpotSimilarityFactory, DistributedQueryElevationComponentTest,
TestSQLHandlerNonCloud, CoreMergeIndexesAdminHandlerTest,
RAMDirectoryFactoryTest, VersionInfoTest, TestSolrFieldCacheBean,
TestInitParams, TestInPlaceUpdatesDistrib, TestCoreBackup, TestFiltering,
JSONWriterTest, SchemaVersionSpecificBehaviorTest, SimpleMLTQParserTest,
TestTrie, TestStressUserVersions, TestStressInPlaceUpdates, QueryResultKeyTest,
StatelessScriptUpdateProcessorFactoryTest, TestLeaderElectionWithEmptyReplica,
TestReloadAndDeleteDocs, LIRRollingUpdatesTest, TestSurroundQueryParser,
LeaderFailoverAfterPartitionTest, TestRequestStatusCollectionAPI,
DateMathParserTest, BasicDistributedZkTest, TestJoin, DateFieldTest,
MetricsConfigTest, WrapperMergePolicyFactoryTest, StatsComponentTest,
DeleteNodeTest, AtomicUpdateProcessorFactoryTest, TestReload,
OpenCloseCoreStressTest, TestAnalyzeInfixSuggestions,
BlockJoinFacetDistribTest, TestCloudManagedSchema, TestLRUStatsCache,
TestConfigOverlay, TestBinaryField, BasicZkTest, SchemaApiFailureTest,
TestExceedMaxTermLength, MBeansHandlerTest, BaseCdcrDistributedZkTest,
TlogReplayBufferedWhileIndexingTest, ForceLeaderTest,
ConcurrentDeleteAndCreateCollectionTest, TestIndexSearcher,
TestMaxScoreQueryParser, BlockJoinFacetSimpleTest, TestBlobHandler,
TestRuleBasedAuthorizationPlugin, SpellingQueryConverterTest,
BigEndianAscendingWordDeserializerTest, AlternateDirectoryTest, CopyFieldTest,
TestPhraseSuggestions, HdfsDirectoryTest, ResourceLoaderTest, TestQueryTypes,
RecoveryZkTest, TestRecovery, SystemLogListenerTest, TestFieldSortValues,
TestSubQueryTransformerDistrib, BinaryUpdateRequestHandlerTest,
MetricTriggerTest, PolyFieldTest, HdfsBasicDistributedZk2Test,
OverseerRolesTest, TestFieldCollectionResource, RecoveryAfterSoftCommitTest,
TestPostingsSolrHighlighter, TestCollectionsAPIViaSolrCloudCluster,
SolrIndexMetricsTest, ZkNodePropsTest, TestSystemIdResolver,
AutoscalingHistoryHandlerTest, TestCloudInspectUtil, DeleteInactiveReplicaTest,
TestCoreAdminApis, SpatialFilterTest, ShufflingReplicaListTransformerTest,
TimeRoutedAliasUpdateProcessorTest, TestStreamBody, CollectionPropsTest,
TestReloadDeadlock, TestFieldCache, TriggerSetPropertiesIntegrationTest,
LeaderVoteWaitTimeoutTest]
[junit4] Completed [226/805 (1!)] on J0 in 27.05s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 45808 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/640/consoleText
[repro] Revision: d691ced6310b6cd03357ec3ec2a68ecabc943fac
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=LeaderVoteWaitTimeoutTest
-Dtests.method=testMostInSyncReplicasCanWinElection
-Dtests.seed=86D8570A7971909 -Dtests.slow=true -Dtests.locale=es-ES
-Dtests.timezone=Europe/Astrakhan -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] LeaderVoteWaitTimeoutTest
[repro] ant compile-test
[...truncated 3342 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=86D8570A7971909
-Dtests.slow=true -Dtests.locale=es-ES -Dtests.timezone=Europe/Astrakhan
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 78 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
[...truncated 70 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
