Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4630/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
Error Message:
Timeout waiting for 1x3 collection null Live Nodes: [127.0.0.1:55384_solr,
127.0.0.1:55390_solr, 127.0.0.1:55396_solr, 127.0.0.1:55402_solr] Last
available state:
DocCollection(collection1//collections/collection1/state.json/21)={
"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:55384/solr";,
"node_name":"127.0.0.1:55384_solr", "state":"recovering",
"type":"NRT", "force_set_state":"false"}, "core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:55390/solr";,
"node_name":"127.0.0.1:55390_solr", "state":"down",
"type":"NRT", "force_set_state":"false"}, "core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:55396/solr";,
"node_name":"127.0.0.1:55396_solr", "state":"active",
"type":"NRT", "force_set_state":"false",
"leader":"true"}}}}, "router":{"name":"compositeId"},
"maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"3",
"tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Timeout waiting for 1x3 collection
null
Live Nodes: [127.0.0.1:55384_solr, 127.0.0.1:55390_solr, 127.0.0.1:55396_solr,
127.0.0.1:55402_solr]
Last available state:
DocCollection(collection1//collections/collection1/state.json/21)={
"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:55384/solr";,
"node_name":"127.0.0.1:55384_solr",
"state":"recovering",
"type":"NRT",
"force_set_state":"false"},
"core_node64":{
"core":"collection1_shard1_replica_n63",
"base_url":"http://127.0.0.1:55390/solr";,
"node_name":"127.0.0.1:55390_solr",
"state":"down",
"type":"NRT",
"force_set_state":"false"},
"core_node66":{
"core":"collection1_shard1_replica_n65",
"base_url":"http://127.0.0.1:55396/solr";,
"node_name":"127.0.0.1:55396_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false",
"leader":"true"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at
__randomizedtesting.SeedInfo.seed([B3451031C8378AF2:1B590C8B0A77BED8]: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:200)
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 13631 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/init-core-data-001
[junit4] 2> 1733103 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001
[junit4] 2> 1733103 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1733104 INFO (Thread-2302) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1733104 INFO (Thread-2302) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1733123 ERROR (Thread-2302) [ ] 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> 1733207 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:55356
[junit4] 2> 1733231 INFO (zkConnectionManagerCallback-3467-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733236 INFO (jetty-launcher-3464-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> 1733237 INFO (jetty-launcher-3464-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> 1733238 INFO (jetty-launcher-3464-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> 1733239 INFO (jetty-launcher-3464-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> 1733242 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1733242 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1733242 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1733243 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1733243 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1733243 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1733243 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1fb020fd{/solr,null,AVAILABLE}
[junit4] 2> 1733243 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@753e0e87{/solr,null,AVAILABLE}
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3fbdcc93{HTTP/1.1,[http/1.1]}{127.0.0.1:55359}
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.e.j.s.Server Started @1733313ms
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55359}
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1733245 ERROR (jetty-launcher-3464-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:21:59.868892Z
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3d9d699d{HTTP/1.1,[http/1.1]}{127.0.0.1:55358}
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.e.j.s.Server Started @1733314ms
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55358}
[junit4] 2> 1733246 ERROR (jetty-launcher-3464-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1733245 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@25b8714b{/solr,null,AVAILABLE}
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1733246 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733247 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:21:59.870009Z
[junit4] 2> 1733248 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@21bb0cc7{HTTP/1.1,[http/1.1]}{127.0.0.1:55360}
[junit4] 2> 1733248 INFO (jetty-launcher-3464-thread-2) [ ]
o.e.j.s.Server Started @1733317ms
[junit4] 2> 1733248 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55360}
[junit4] 2> 1733248 ERROR (jetty-launcher-3464-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1733248 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1733250 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1733250 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1733250 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733250 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:21:59.873433Z
[junit4] 2> 1733251 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1733251 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1733251 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1733252 INFO (zkConnectionManagerCallback-3471-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733252 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@58474499{/solr,null,AVAILABLE}
[junit4] 2> 1733252 INFO (zkConnectionManagerCallback-3469-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733252 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3ea91153{HTTP/1.1,[http/1.1]}{127.0.0.1:55364}
[junit4] 2> 1733252 INFO (jetty-launcher-3464-thread-4) [ ]
o.e.j.s.Server Started @1733321ms
[junit4] 2> 1733252 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55364}
[junit4] 2> 1733253 ERROR (jetty-launcher-3464-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1733253 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1733253 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1733253 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1733253 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1733253 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:21:59.876866Z
[junit4] 2> 1733254 INFO (zkConnectionManagerCallback-3473-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733254 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1733254 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1733255 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1733256 INFO (zkConnectionManagerCallback-3475-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733260 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1733261 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
0x10036840c7d0002, likely client has closed socket
[junit4] 2> 1733263 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
0x10036840c7d0003, likely client has closed socket
[junit4] 2> 1733265 INFO (jetty-launcher-3464-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1733265 INFO (jetty-launcher-3464-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1733267 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
0x10036840c7d0004, likely client has closed socket
[junit4] 2> 1733268 INFO (zkConnectionManagerCallback-3481-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733269 INFO (zkConnectionManagerCallback-3483-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733270 INFO (jetty-launcher-3464-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1733271 INFO (jetty-launcher-3464-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1733271 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
0x10036840c7d0005, likely client has closed socket
[junit4] 2> 1733273 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
0x10036840c7d0006, likely client has closed socket
[junit4] 2> 1733274 INFO (zkConnectionManagerCallback-3491-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733274 INFO (zkConnectionManagerCallback-3488-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733274 INFO (zkConnectionManagerCallback-3493-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733275 INFO (zkConnectionManagerCallback-3495-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733276 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
0x10036840c7d0008, likely client has closed socket
[junit4] 2> 1733276 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
0x10036840c7d0007, likely client has closed socket
[junit4] 2> 1733280 INFO (zkConnectionManagerCallback-3497-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733281 INFO (zkConnectionManagerCallback-3499-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733587 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1733588 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1733589 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55364_solr
[junit4] 2> 1733591 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55359_solr
[junit4] 2> 1733591 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.Overseer Overseer
(id=72117534811029515-127.0.0.1:55364_solr-n_0000000000) starting
[junit4] 2> 1733593 INFO (zkCallback-3494-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1733594 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1733597 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1733603 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1733606 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1733607 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1733607 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55358_solr
[junit4] 2> 1733609 INFO (zkConnectionManagerCallback-3512-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733611 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1733612 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1733612 INFO (zkCallback-3494-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1733616 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1733620 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1733624 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1733630 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1733630 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55364_solr
[junit4] 2> 1733706 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1733716 INFO (zkCallback-3494-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1733716 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1733717 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1733717 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1733725 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1733725 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55360_solr
[junit4] 2> 1733735 INFO (zkCallback-3494-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733736 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733736 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733736 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733740 INFO (zkCallback-3511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1733749 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55364.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733757 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55359.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733767 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55359.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733767 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55359.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733769 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node3/.
[junit4] 2> 1733772 INFO (zkConnectionManagerCallback-3517-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733773 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1733775 INFO (jetty-launcher-3464-thread-3)
[n:127.0.0.1:55359_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1733942 WARN (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register
gauge
[junit4] 2> javax.management.InstanceNotFoundException:
solr_55364:dom1=solr,dom2=jvm,name0=memory,name1=pools,name2=G1-Eden-Space,name=usage
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1083)
~[?:?]
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:423)
~[?:?]
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:411)
~[?:?]
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
~[?:?]
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
~[java/:?]
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
[java/:?]
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
[java/:?]
[junit4] 2> at java.util.HashMap.forEach(HashMap.java:1340) [?:?]
[junit4] 2> at
org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
[java/:?]
[junit4] 2> at
org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:110)
[java/:?]
[junit4] 2> at
org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
[java/:?]
[junit4] 2> at
org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:930)
[java/:?]
[junit4] 2> at
org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:866)
[java/:?]
[junit4] 2> at
org.apache.solr.core.CoreContainer.load(CoreContainer.java:553) [java/:?]
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:253)
[java/:?]
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:173)
[java/:?]
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:741)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1477)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1542)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1186)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1023)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:473)
[jetty-servlet-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:308)
[java/:?]
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
[jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
[jetty-util-9.4.10.v20180503.jar:9.4.10.v20180503]
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:396)
[java/:?]
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:369)
[java/:?]
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:392)
[java/:?]
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:248)
[java/:?]
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192)
[java/:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[?:?]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?]
[junit4] 2> 1733977 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55358.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733980 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55364.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733980 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55364.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733986 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55358.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733987 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55358.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1733988 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node1/.
[junit4] 2> 1733993 INFO (zkConnectionManagerCallback-3522-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1733997 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1733997 INFO (jetty-launcher-3464-thread-4)
[n:127.0.0.1:55364_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node4/.
[junit4] 2> 1734001 INFO (jetty-launcher-3464-thread-1)
[n:127.0.0.1:55358_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1734128 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55360.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734138 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55360.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734138 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55360.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734140 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node2/.
[junit4] 2> 1734145 INFO (zkConnectionManagerCallback-3527-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734147 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1734149 INFO (jetty-launcher-3464-thread-2)
[n:127.0.0.1:55360_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1734180 INFO (zkConnectionManagerCallback-3529-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734184 INFO (zkConnectionManagerCallback-3534-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734186 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1734188 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55356/solr ready
[junit4] 2> 1734197 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3fbdcc93{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1734198 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1653693731
[junit4] 2> 1734199 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1734199 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@67d2dfeb: rootName =
solr_55359, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@5be87fff
[junit4] 2> 1734208 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1734208 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@57d53395: rootName =
solr_55359, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@21aacd43
[junit4] 2> 1734212 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1734212 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5602377d: rootName =
solr_55359, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2d64f9e3
[junit4] 2> 1734219 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55359_solr
[junit4] 2> 1734223 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1734225 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1734227 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734227 INFO (zkCallback-3511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734227 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734227 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734227 INFO (zkCallback-3494-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734231 INFO (zkCallback-3521-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734231 INFO (zkCallback-3516-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734231 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734232 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734233 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@753e0e87{/solr,null,UNAVAILABLE}
[junit4] 2> 1734234 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1734235 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-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> 1734237 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1734237 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1734237 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1734238 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@221b5142{/solr,null,AVAILABLE}
[junit4] 2> 1734238 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@101ee116{HTTP/1.1,[http/1.1]}{127.0.0.1:55385}
[junit4] 2> 1734238 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.Server Started @1734307ms
[junit4] 2> 1734238 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55384}
[junit4] 2> 1734239 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1734239 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1734239 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1734239 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1734239 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1734239 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:22:00.862641Z
[junit4] 2> 1734241 INFO (zkConnectionManagerCallback-3536-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734242 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1734250 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1734251 INFO (zkConnectionManagerCallback-3540-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734253 INFO (zkConnectionManagerCallback-3542-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734270 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1734274 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1734277 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1734277 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55384_solr
[junit4] 2> 1734282 INFO (zkCallback-3511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3521-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734282 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734283 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734493 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55384.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734507 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55384.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734507 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55384.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734508 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node3/.
[junit4] 2> 1734512 INFO (zkConnectionManagerCallback-3549-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734514 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1734516 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55384_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1734585 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:55385/solr. Proxy: http://127.0.0.1:55384/solr
[junit4] 2> 1734586 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3ea91153{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1734587 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=388267083
[junit4] 2> 1734587 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1734587 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6c309bda: rootName =
solr_55364, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@b0a5011
[junit4] 2> 1734598 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1734598 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6ec5b77c: rootName =
solr_55364, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@21aacd43
[junit4] 2> 1734604 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1734604 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@128bb290: rootName =
solr_55364, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2d64f9e3
[junit4] 2> 1734606 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55364_solr
[junit4] 2> 1734609 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1734612 INFO (zkCallback-3496-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734613 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734613 INFO (zkCallback-3511-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734613 INFO (zkCallback-3521-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734613 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734613 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734614 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734619 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734619 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029515-127.0.0.1:55364_solr-n_0000000000) closing
[junit4] 2> 1734619 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734620 INFO
(OverseerStateUpdate-72117534811029515-127.0.0.1:55364_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55364_solr
[junit4] 2> 1734620 WARN
(OverseerAutoScalingTriggerThread-72117534811029515-127.0.0.1:55364_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1734628 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@58474499{/solr,null,UNAVAILABLE}
[junit4] 2> 1734628 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55358_solr
[junit4] 2> 1734628 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1734630 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-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> 1734631 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029513-127.0.0.1:55358_solr-n_0000000002) starting
[junit4] 2> 1734633 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1734633 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1734633 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1734634 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4cfa8e39{/solr,null,AVAILABLE}
[junit4] 2> 1734634 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@21582c08{HTTP/1.1,[http/1.1]}{127.0.0.1:55391}
[junit4] 2> 1734634 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.Server Started @1734703ms
[junit4] 2> 1734634 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55390}
[junit4] 2> 1734635 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1734635 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1734635 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1734635 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1734636 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1734636 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:22:01.259076Z
[junit4] 2> 1734638 INFO (zkConnectionManagerCallback-3551-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734639 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1734660 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1734662 INFO (zkConnectionManagerCallback-3555-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734666 INFO (zkConnectionManagerCallback-3557-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734680 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1734684 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1734688 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1734689 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55390_solr
[junit4] 2> 1734693 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734693 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734694 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734694 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734694 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734694 INFO (zkCallback-3521-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734694 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734695 INFO (zkCallback-3556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1734881 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55390.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734893 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55390.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734893 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55390.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1734895 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node4/.
[junit4] 2> 1734900 INFO (zkConnectionManagerCallback-3564-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1734906 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1734909 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55390_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1734960 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:55391/solr. Proxy: http://127.0.0.1:55390/solr
[junit4] 2> 1734960 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3d9d699d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1734961 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=491309461
[junit4] 2> 1734961 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1734961 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@637e0771: rootName =
solr_55358, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@55d5db36
[junit4] 2> 1734968 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1734968 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@38e429d1: rootName =
solr_55358, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@21aacd43
[junit4] 2> 1734972 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1734972 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@48ec24be: rootName =
solr_55358, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2d64f9e3
[junit4] 2> 1734974 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55358_solr
[junit4] 2> 1734977 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3492-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3521-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734977 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734978 INFO (zkCallback-3563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734978 INFO (zkCallback-3556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1734979 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1734980 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029513-127.0.0.1:55358_solr-n_0000000002) closing
[junit4] 2> 1734980 INFO
(OverseerStateUpdate-72117534811029513-127.0.0.1:55358_solr-n_0000000002) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55358_solr
[junit4] 2> 1734980 WARN
(OverseerAutoScalingTriggerThread-72117534811029513-127.0.0.1:55358_solr-n_0000000002)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1734987 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55360_solr
[junit4] 2> 1734988 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1fb020fd{/solr,null,UNAVAILABLE}
[junit4] 2> 1734988 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1734990 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029516-127.0.0.1:55360_solr-n_0000000003) starting
[junit4] 2> 1734992 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-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> 1734995 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1734995 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1734995 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1734996 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@20902b8d{/solr,null,AVAILABLE}
[junit4] 2> 1735003 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@71d6859c{HTTP/1.1,[http/1.1]}{127.0.0.1:55397}
[junit4] 2> 1735003 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.Server Started @1735071ms
[junit4] 2> 1735003 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55396}
[junit4] 2> 1735004 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1735004 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1735005 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1735005 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1735005 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1735005 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:22:01.628619Z
[junit4] 2> 1735009 INFO (zkConnectionManagerCallback-3566-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735010 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1735020 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1735022 INFO (zkConnectionManagerCallback-3570-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735026 INFO (zkConnectionManagerCallback-3572-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735062 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1735067 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1735083 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1735084 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55396_solr
[junit4] 2> 1735086 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735086 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735087 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735087 INFO (zkCallback-3563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735087 INFO (zkCallback-3556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735086 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735087 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735088 INFO (zkCallback-3571-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735269 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55396.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735282 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55396.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735282 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55396.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735288 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node1/.
[junit4] 2> 1735294 INFO (zkConnectionManagerCallback-3579-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735297 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1735300 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55396_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1735362 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:55397/solr. Proxy: http://127.0.0.1:55396/solr
[junit4] 2> 1735364 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@21bb0cc7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1735364 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1747086890
[junit4] 2> 1735365 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1735365 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@181283a1: rootName =
solr_55360, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@64edbc37
[junit4] 2> 1735371 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1735371 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7f118e1e: rootName =
solr_55360, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@21aacd43
[junit4] 2> 1735375 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1735375 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@12d2b3c2: rootName =
solr_55360, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@2d64f9e3
[junit4] 2> 1735380 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:55360_solr
[junit4] 2> 1735385 INFO (zkCallback-3526-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735385 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735385 INFO (zkCallback-3498-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735385 INFO (zkCallback-3556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735386 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735386 INFO (zkCallback-3563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735386 INFO (zkCallback-3571-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735401 INFO (zkCallback-3578-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735401 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1735402 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1735411 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029516-127.0.0.1:55360_solr-n_0000000003) closing
[junit4] 2> 1735411 INFO
(OverseerStateUpdate-72117534811029516-127.0.0.1:55360_solr-n_0000000003) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55360_solr
[junit4] 2> 1735411 WARN
(OverseerAutoScalingTriggerThread-72117534811029516-127.0.0.1:55360_solr-n_0000000003)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 1735441 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55384_solr
[junit4] 2> 1735442 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@25b8714b{/solr,null,UNAVAILABLE}
[junit4] 2> 1735442 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1735444 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72117534811029525-127.0.0.1:55384_solr-n_0000000004) starting
[junit4] 2> 1735444 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-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> 1735445 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1735445 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1735445 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1735445 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@664fba86{/solr,null,AVAILABLE}
[junit4] 2> 1735446 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5951296e{HTTP/1.1,[http/1.1]}{127.0.0.1:55403}
[junit4] 2> 1735447 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.e.j.s.Server Started @1735516ms
[junit4] 2> 1735447 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55402}
[junit4] 2> 1735447 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1735447 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1735448 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1735448 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1735448 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1735448 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-05-14T20:22:02.071266Z
[junit4] 2> 1735450 INFO (zkConnectionManagerCallback-3581-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735452 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1735522 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55356/solr
[junit4] 2> 1735546 INFO (zkConnectionManagerCallback-3585-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735569 INFO (zkConnectionManagerCallback-3587-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735606 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1735623 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1735629 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1735629 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55402_solr
[junit4] 2> 1735632 INFO (zkCallback-3548-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735632 INFO (zkCallback-3533-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735632 INFO (zkCallback-3563-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735632 INFO (zkCallback-3578-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735632 INFO (zkCallback-3556-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735633 INFO (zkCallback-3541-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735634 INFO (zkCallback-3586-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735635 INFO (zkCallback-3571-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1735826 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55402.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735838 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55402.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735838 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55402.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@191bf6fe
[junit4] 2> 1735840 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001/tempDir-001/node2/.
[junit4] 2> 1735855 INFO (zkConnectionManagerCallback-3594-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1735858 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1735862 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker)
[n:127.0.0.1:55402_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:55356/solr ready
[junit4] 2> 1735926 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL:
http://127.0.0.1:55403/solr. Proxy: http://127.0.0.1:55402/solr
[junit4] 2> 1735974 INFO (SocketProxy-Acceptor-55384) [ ]
o.a.s.c.SocketProxy accepted
Socket[addr=/127.0.0.1,port=55412,localport=55384], receiveBufferSize:408300
[junit4] 2> 1735975 INFO (SocketProxy-Acceptor-55384) [ ]
o.a.s.c.SocketProxy proxy connection
Socket[addr=/127.0.0.1,port=55385,localport=55413], receiveBufferSize=408300
[junit4] 2> 1735977 INFO (qtp1609999014-12837) [n:127.0.0.1:55384_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=0&replicationFactor=3&name=collection1&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1735980 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1735982 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to
/configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 1735986 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to
/configs/collection1.AUTOCREATED/protwords.txt
[junit4] 2> 1735989 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to
/configs/collection1.AUTOCREATED/solrconfig.xml
[junit4] 2> 1735996 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to
/configs/collection1.AUTOCREATED/synonyms.txt
[junit4] 2> 1735999 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to
/configs/collection1.AUTOCREATED/stopwords.txt
[junit4] 2> 1736003 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/contractions_it.txt to
/configs/collection1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1736006 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/contractions_ca.txt to
/configs/collection1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1736010 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stemdict_nl.txt to
/configs/collection1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1736013 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_hy.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1736017 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_no.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1736020 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_id.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1736024 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_da.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1736027 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ga.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1736030 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_hi.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1736033 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ja.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1736036 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_pt.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1736039 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_hu.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1736058 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_el.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1736062 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ru.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1736066 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_tr.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1736069 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_ar.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1736072 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/userdict_ja.txt to
/configs/collection1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1736075 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_eu.txt to
/configs/collection1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1736077 INFO (OverseerThreadFactory-4961-thread-1) [ ]
o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/lang/stopwords_it.txt to /configs/collection1.
[...truncated too long message...]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 29712 INFO (coreCloseExecutor-84-thread-1)
[n:127.0.0.1:52411_solr c:collection1 s:shard1 r:core_node64
x:collection1_shard1_replica_n63] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=7525678
[junit4] 2> 29713 INFO (jetty-closer-2-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2e77486b{/solr,null,UNAVAILABLE}
[junit4] 2> 29714 INFO (jetty-closer-2-thread-3) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 29803 INFO (zkCallback-93-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 29803 INFO (zkCallback-108-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 29803 INFO (zkCallback-108-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 29803 INFO (zkCallback-93-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [0])
[junit4] 2> 30054 INFO (zkCallback-108-thread-2) [n:127.0.0.1:52421_solr
c:collection1 s:shard1 r:core_node66 x:collection1_shard1_replica_n65]
o.a.s.c.SolrCore [collection1_shard1_replica_n65] CLOSING SolrCore
org.apache.solr.core.SolrCore@4efc606e
[junit4] 2> 30054 INFO (zkCallback-108-thread-2) [n:127.0.0.1:52421_solr
c:collection1 s:shard1 r:core_node66 x:collection1_shard1_replica_n65]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.replica_n65, tag=4efc606e
[junit4] 2> 30054 INFO (zkCallback-108-thread-2) [n:127.0.0.1:52421_solr
c:collection1 s:shard1 r:core_node66 x:collection1_shard1_replica_n65]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@10013ffa: rootName =
solr_52421, 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@fbaca76
[junit4] 2> 30066 INFO (zkCallback-108-thread-2) [n:127.0.0.1:52421_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=4efc606e
[junit4] 2> 34289 ERROR
(recoveryExecutor-90-thread-1-processing-n:127.0.0.1:52411_solr
x:collection1_shard1_replica_n63 c:collection1 s:shard1 r:core_node64)
[n:127.0.0.1:52411_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> 34289 WARN
(updateExecutor-89-thread-2-processing-n:127.0.0.1:52411_solr
x:collection1_shard1_replica_n63 c:collection1 s:shard1 r:core_node64)
[n:127.0.0.1:52411_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> 34290 INFO (jetty-closer-2-thread-1) [ ] o.a.s.c.Overseer
Overseer (id=72117730020884505-127.0.0.1:52411_solr-n_0000000005) closing
[junit4] 2> 34291 WARN
(OverseerAutoScalingTriggerThread-72117730020884505-127.0.0.1:52411_solr-n_0000000005)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 34291 INFO
(OverseerStateUpdate-72117730020884505-127.0.0.1:52411_solr-n_0000000005) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52411_solr
[junit4] 2> 34296 INFO (jetty-closer-2-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@306fdd08{/solr,null,UNAVAILABLE}
[junit4] 2> 34297 INFO (jetty-closer-2-thread-1) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 34297 INFO (zkCallback-108-thread-2) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52421_solr
[junit4] 2> 37071 ERROR
(recoveryExecutor-105-thread-1-processing-n:127.0.0.1:52421_solr
x:collection1_shard1_replica_n65 c:collection1 s:shard1 r:core_node66)
[n:127.0.0.1:52421_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> 37077 INFO (jetty-closer-2-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@604d741b{/solr,null,UNAVAILABLE}
[junit4] 2> 37077 INFO (jetty-closer-2-thread-2) [ ] o.e.j.s.session
node0 Stopped scavenging
[junit4] 2> 37079 ERROR
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-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> 37081 INFO
(SUITE-LeaderVoteWaitTimeoutTest-seed#[B3451031C8378AF2]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:52352 52352
[junit4] 2> 37096 INFO (Thread-0) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:52352 52352
[junit4] 2> 37587 WARN (Thread-0) [ ] 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> 17 /solr/aliases.json
[junit4] 2> 17 /solr/clusterprops.json
[junit4] 2> 8 /solr/security.json
[junit4] 2> 3
/solr/configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/configs/collection1.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/collections/collection1/state.json
[junit4] 2> 17 /solr/clusterstate.json
[junit4] 2> 4
/solr/overseer_elect/election/72117730020884492-127.0.0.1:52362_solr-n_0000000000
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 17 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 3 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 3 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderVoteWaitTimeoutTest_B3451031C8378AF2-001
[junit4] 2> May 14, 2018 9:12:10 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): {},
docValues:{}, maxPointsInLeafNode=722, maxMBSortInHeap=5.018695210352812,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@72a5e6d),
locale=pt-CV, timezone=MST7MDT
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=23167512,total=135266304
[junit4] 2> NOTE: All tests run in this JVM: [LeaderVoteWaitTimeoutTest]
[junit4] Completed [1/5 (1!)] on J0 in 35.98s, 2 tests, 1 failure, 1 error
<<< FAILURES!
[...truncated 32 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1568:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1092:
There were test failures: 5 suites, 10 tests, 1 error, 1 failure [seed:
B3451031C8378AF2]
Total time: 1 minute 54 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all
warnings.
<Git Blamer> GIT_COMMIT=02849fb707626cf4312f59324fd894be117787c1,
workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4629
Recording test results
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting
ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
