Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1767/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
REGRESSION: org.apache.solr.cloud.OverseerTest.testOverseerFailure
Error Message:
Could not register as the leader because creating the ephemeral registration
node in ZooKeeper failed
Stack Trace:
org.apache.solr.common.SolrException: Could not register as the leader because
creating the ephemeral registration node in ZooKeeper failed
at
__randomizedtesting.SeedInfo.seed([3D207A17C93FB1B6:3928F5E4DB9A5E97]:0)
at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:144)
at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:163)
at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:125)
at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:155)
at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:155)
at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:314)
at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:221)
at
org.apache.solr.cloud.OverseerTest$MockZKController.publishState(OverseerTest.java:155)
at
org.apache.solr.cloud.OverseerTest.testOverseerFailure(OverseerTest.java:660)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.zookeeper.KeeperException$NodeExistsException:
KeeperErrorCode = NodeExists for /collections/collection1/leaders/shard1
at org.apache.zookeeper.KeeperException.create(KeeperException.java:119)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
at
org.apache.solr.common.cloud.SolrZkClient$11.execute(SolrZkClient.java:457)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:454)
at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:411)
at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:398)
at
org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:136)
at
org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:131)
... 48 more
REGRESSION: org.apache.solr.schema.TestCloudSchemaless.testDistribSearch
Error Message:
Timeout occured while waiting response from server at:
https://127.0.0.1:50992/w_/d/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting
response from server at: https://127.0.0.1:50992/w_/d/collection1
at
__randomizedtesting.SeedInfo.seed([3D207A17C93FB1B6:BCC6F40FBE60D18A]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at
org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
at
org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.GeneratedMethodAccessor48.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:152)
at java.net.SocketInputStream.read(SocketInputStream.java:122)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
at sun.security.ssl.InputRecord.read(InputRecord.java:480)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
at
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
at
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448)
... 46 more
Build Log:
[...truncated 11106 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.OverseerTest-3D207A17C93FB1B6-001/init-core-data-001
[junit4] 2> 2104197 T3852 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 2104197 T3852 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 2104198 T3852 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 2104204 T3852 oas.SolrTestCaseJ4.setUp ###Starting
testBadQueueItem
[junit4] 2> 2104208 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2104211 T3853 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2104311 T3852 oasc.ZkTestServer.run start zk server on
port:50239
[junit4] 2> 2104313 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2104323 T3859 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@22ccd64 name:ZooKeeperConnection
Watcher:127.0.0.1:50239 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 2104324 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2104330 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2104334 T3861 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7e94d888
name:ZooKeeperConnection Watcher:127.0.0.1:50239 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2104335 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2104335 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2104347 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2104352 T3863 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1337482e
name:ZooKeeperConnection Watcher:127.0.0.1:50239/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2104352 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2104353 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2104359 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2104362 T3865 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@562f726a
name:ZooKeeperConnection Watcher:127.0.0.1:50239/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2104363 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2104365 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2104371 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2104382 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50239_solr
[junit4] 2> 2104383 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2104389 T3852 oasc.Overseer.start Overseer
(id=92268525262995459-127.0.0.1:50239_solr-n_0000000000) starting
[junit4] 2> 2104394 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2104407 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2104413 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2104421 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2104429 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2104439 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2104455 T3867 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2104457 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2104459 T3866 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2104464 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2104468 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2104476 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2104481 T3869 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1bc089f1
name:ZooKeeperConnection Watcher:127.0.0.1:50239/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2104481 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2104484 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2104491 T3852 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1
[junit4] 2> 2104505 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2104510 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2104512 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2104512 T3866
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 2104512 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard3
[junit4] 2> 2104521 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2104521 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2105007 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard3/election
[junit4] 2> 2105029 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard3
[junit4] 2> 2105043 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2105046 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2105059 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2105059 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2105059 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2105070 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2105074 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2105075 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2105548 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2105575 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2105589 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2105593 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2105603 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2105604 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2105604 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 2105610 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2105612 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2105613 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106093 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 2106111 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 2106124 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2106128 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2106241 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106241 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106282 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2106286 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2106288 T3866
oasc.Overseer$ClusterStateUpdater.checkKeyExistence ERROR Skipping invalid
Overseer message because it has no collection specified: {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":"",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2106296 T3866
oasc.Overseer$ClusterStateUpdater.checkKeyExistence ERROR Skipping invalid
Overseer message because it has no collection specified: {
[junit4] 2> "operation":"deletecore",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":""}
[junit4] 2> 2106302 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2106308 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection2",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2106308 T3866
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection2 with shards [shard1, shard2, shard3]
[junit4] 2> 2106308 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard3
[junit4] 2> 2106417 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106417 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106792 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leader_elect/shard3/election
[junit4] 2> 2106818 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leaders/shard3
[junit4] 2> 2106834 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2106839 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2106853 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection2",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2106853 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2106853 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2106859 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2106863 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2106863 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2107339 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leader_elect/shard1/election
[junit4] 2> 2107359 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leaders/shard1
[junit4] 2> 2107376 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2107382 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2107396 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection2",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2107396 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2107396 T3866 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 2107401 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2107513 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2107513 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2107884 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leader_elect/shard2/election
[junit4] 2> 2107903 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection2/leaders/shard2
[junit4] 2> 2107916 T3872 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2107919 T3866 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2108032 T3870 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2108032 T3871 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2108083 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50239 50239
[junit4] 2> 2108227 T3853 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50239 50239
[junit4] 2> 2108460 T3852 oas.SolrTestCaseJ4.tearDown ###Ending
testBadQueueItem
[junit4] 2> 2108461 T3866 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50239_solr
[junit4] 2> 2108465 T3873
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2108474 T3852 oas.SolrTestCaseJ4.setUp ###Starting testReplay
[junit4] 2> 2108479 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2108480 T3874 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2108584 T3852 oasc.ZkTestServer.run start zk server on
port:50245
[junit4] 2> 2108586 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2108599 T3880 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@233f03a4
name:ZooKeeperConnection Watcher:127.0.0.1:50245/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2108599 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2108601 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2108603 T3882 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@480467a2
name:ZooKeeperConnection Watcher:127.0.0.1:50245 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2108603 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2108618 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2108622 T3884 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@300a1423
name:ZooKeeperConnection Watcher:127.0.0.1:50245 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2108623 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2108623 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2108636 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2108645 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2108654 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2108658 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2108672 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2108684 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2108687 T3886 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@129ae596
name:ZooKeeperConnection Watcher:127.0.0.1:50245/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2108688 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2108690 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2108696 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2108708 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50245_solr
[junit4] 2> 2108709 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2108717 T3852 oasc.Overseer.start Overseer
(id=92268525542834179-127.0.0.1:50245_solr-n_0000000000) starting
[junit4] 2> 2108722 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2108733 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2108746 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2108756 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2108769 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2108785 T3888 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2108794 T3887 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2108797 T3887 oasc.Overseer$ClusterStateUpdater.run Replaying
operations from work queue.
[junit4] 2> 2108799 T3887 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr";,
[junit4] 2> "node_name":"node1",
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"}
[junit4] 2> 2108803 T3889 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2108812 T3887 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr";,
[junit4] 2> "node_name":"node1",
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core2",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"}
[junit4] 2> 2108815 T3889 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2108819 T3887 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2108827 T3887 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2108828 T3887 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr";,
[junit4] 2> "node_name":"node1",
[junit4] 2> "shard":"s1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core3",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"}
[junit4] 2> 2108839 T3890 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2108843 T3889 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2108902 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50245 50245
[junit4] 2> 2108914 T3874 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50245 50245
[junit4] 2> 2109127 T3852 oas.SolrTestCaseJ4.tearDown ###Ending testReplay
[junit4] 2> 2109128 T3887 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50245_solr
[junit4] 2> 2109129 T3891
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2109139 T3852 oas.SolrTestCaseJ4.setUp ###Starting
testDoubleAssignment
[junit4] 2> 2109140 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2109141 T3892 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2109242 T3852 oasc.ZkTestServer.run start zk server on
port:50250
[junit4] 2> 2109244 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2109250 T3898 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2c35296a
name:ZooKeeperConnection Watcher:127.0.0.1:50250/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2109251 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2109252 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2109254 T3900 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3a37aea1
name:ZooKeeperConnection Watcher:127.0.0.1:50250 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2109255 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2109262 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2109265 T3902 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4e086813
name:ZooKeeperConnection Watcher:127.0.0.1:50250 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2109265 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2109265 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2109273 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2109281 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2109286 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2109290 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2109299 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2109301 T3904 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4449847a
name:ZooKeeperConnection Watcher:127.0.0.1:50250/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2109302 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2109305 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2109313 T3852 oascc.SolrZkClient.makePath makePath:
/live_nodes/node1
[junit4] 2> 2109321 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2109324 T3908 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2f22c2fd
name:ZooKeeperConnection Watcher:127.0.0.1:50250/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2109324 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2109327 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2109332 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2109341 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50250_solr
[junit4] 2> 2109342 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2109349 T3852 oasc.Overseer.start Overseer
(id=92268525586022404-127.0.0.1:50250_solr-n_0000000000) starting
[junit4] 2> 2109353 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2109362 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2109370 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2109379 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2109386 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2109396 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2109411 T3910 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2109414 T3909 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2109418 T3909 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2109420 T3909 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 2109420 T3909
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1]
[junit4] 2> 2109420 T3909 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2109427 T3911 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2109428 T3905 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2109428 T3906 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2109916 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2109944 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2109962 T3911 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2109963 T3852 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2109966 T3909 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2110080 T3906 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2111468 T3905 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2111472 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2111475 T3913 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23f9f545
name:ZooKeeperConnection Watcher:127.0.0.1:50250/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2111476 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2111481 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2111489 T3852 oascc.SolrZkClient.makePath makePath:
/live_nodes/node1
[junit4] 2> 2111502 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2111502 T3911 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2111508 T3909 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2111510 T3909 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 2111510 T3909 oasc.Overseer$ClusterStateUpdater.updateState
shard=shard1 is already registered
[junit4] 2> 2111518 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2111531 T3911 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2111642 T3906 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2111643 T3914 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2111646 T3852 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2111661 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50250 50250
[junit4] 2> 2111681 T3892 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50250 50250
[junit4] 2> 2111927 T3852 oas.SolrTestCaseJ4.tearDown ###Ending
testDoubleAssignment
[junit4] 2> 2111928 T3909 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50250_solr
[junit4] 2> 2111929 T3915
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2111939 T3852 oas.SolrTestCaseJ4.setUp ###Starting
testStateChange
[junit4] 2> 2111941 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2111944 T3916 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2112046 T3852 oasc.ZkTestServer.run start zk server on
port:50257
[junit4] 2> 2112047 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112059 T3922 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@759902e4
name:ZooKeeperConnection Watcher:127.0.0.1:50257/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112060 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112061 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112067 T3924 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3d426cf9
name:ZooKeeperConnection Watcher:127.0.0.1:50257 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112067 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112079 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112082 T3926 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3e74eb5f
name:ZooKeeperConnection Watcher:127.0.0.1:50257 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112082 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112083 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2112091 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2112098 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2112104 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2112107 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2112115 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112119 T3928 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@785d63e name:ZooKeeperConnection
Watcher:127.0.0.1:50257/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 2112119 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112122 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2112128 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2112138 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50257_solr
[junit4] 2> 2112138 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2112145 T3852 oasc.Overseer.start Overseer
(id=92268525769785347-127.0.0.1:50257_solr-n_0000000000) starting
[junit4] 2> 2112149 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2112157 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2112165 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2112172 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2112181 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2112190 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2112204 T3930 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2112209 T3929 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2112211 T3852 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2112216 T3929 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2112217 T3929 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr";,
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"}
[junit4] 2> 2112217 T3929 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2112223 T3931 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2112224 T3932 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2112265 T3852 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2112269 T3931 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2112273 T3929 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2112274 T3929 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr";,
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"active"}
[junit4] 2> 2112275 T3929 oasc.Overseer$ClusterStateUpdater.updateState
node=core_node1 is already registered
[junit4] 2> 2112275 T3929 oasc.Overseer$ClusterStateUpdater.updateState
shard=shard1 is already registered
[junit4] 2> 2112386 T3932 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 2112430 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50257 50257
[junit4] 2> 2112529 T3916 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50257 50257
[junit4] 2> 2112766 T3852 oas.SolrTestCaseJ4.tearDown ###Ending
testStateChange
[junit4] 2> 2112777 T3929 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50257_solr
[junit4] 2> 2112784 T3933
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2112786 T3852 oas.SolrTestCaseJ4.setUp ###Starting
testShardAssignment
[junit4] 2> 2112808 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2112810 T3934 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2112911 T3852 oasc.ZkTestServer.run start zk server on
port:50262
[junit4] 2> 2112917 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112932 T3940 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@50ef0538
name:ZooKeeperConnection Watcher:127.0.0.1:50262 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112933 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112942 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112945 T3942 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@69ad9456
name:ZooKeeperConnection Watcher:127.0.0.1:50262 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112945 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112946 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2112956 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112959 T3944 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3911d28d
name:ZooKeeperConnection Watcher:127.0.0.1:50262/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112960 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112960 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2112966 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2112968 T3946 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@14523ae9
name:ZooKeeperConnection Watcher:127.0.0.1:50262/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2112969 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2112971 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2112977 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2112987 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50262_solr
[junit4] 2> 2112987 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2112992 T3852 oasc.Overseer.start Overseer
(id=92268525826473987-127.0.0.1:50262_solr-n_0000000000) starting
[junit4] 2> 2112997 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2113006 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2113017 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2113031 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2113038 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2113049 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2113064 T3948 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2113067 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2113069 T3947 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2113073 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2113077 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2113092 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2113098 T3950 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@14618000
name:ZooKeeperConnection Watcher:127.0.0.1:50262/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2113099 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2113105 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2113113 T3852 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1
[junit4] 2> 2113127 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2113135 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2113137 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2113137 T3947
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 2113137 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard3
[junit4] 2> 2113147 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2113148 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2113628 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard3/election
[junit4] 2> 2113650 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard3
[junit4] 2> 2113666 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2113671 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2113681 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2113681 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2113681 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2113687 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2113690 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2113690 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2114172 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2114195 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 2114208 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2114215 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2114227 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2114227 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2114227 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 2114234 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2114237 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2114238 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2114717 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 2114734 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 2114747 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2114751 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2114761 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core4",
[junit4] 2> "core_node_name":"node4",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2114761 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2114761 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard3
[junit4] 2> 2114767 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2114771 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2114772 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2115253 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard3/election
[junit4] 2> 2115279 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2115282 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2115283 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core5",
[junit4] 2> "core_node_name":"node5",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2115283 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2115283 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 2115396 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2115396 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2115781 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 2115805 T3953 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2115808 T3947 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2115810 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core6",
[junit4] 2> "core_node_name":"node6",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 2115810 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=3
[junit4] 2> 2115810 T3947 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 2115922 T3951 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2115922 T3952 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2116306 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 2116338 T3950 oaz.ClientCnxn$EventThread.processEvent ERROR
Error while calling watcher java.util.concurrent.RejectedExecutionException:
Task java.util.concurrent.FutureTask@61d46789 rejected from
java.util.concurrent.ThreadPoolExecutor@3fd9858c[Shutting down, pool size = 2,
active threads = 2, queued tasks = 0, completed tasks = 8]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372)
[junit4] 2> at
java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:201)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 2116339 T3954 oasc.LeaderElector$ElectionWatcher.process WARN
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /collections/collection1/leader_elect/shard2/election
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:285)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:282)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:282)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:358)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:204)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2117838 T3951 oasc.LeaderElector$ElectionWatcher.process WARN
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /collections/collection1/leader_elect/shard3/election
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:285)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:282)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:282)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:94)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:358)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:204)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2117846 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50262 50262
[junit4] 2> 2117857 T3934 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50262 50262
[junit4] 2> 2118058 T3852 oas.SolrTestCaseJ4.tearDown ###Ending
testShardAssignment
[junit4] 2> 2118060 T3947 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50262_solr
[junit4] 2> 2118092 T3955
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2118097 T3852 oas.SolrTestCaseJ4.setUp ###Starting
testPlaceholders
[junit4] 2> 2118100 T3852 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2118101 T3956 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 2118102 T3852 oasc.ZkTestServer.run start zk server on
port:50268
[junit4] 2> 2118109 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2118122 T3962 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2d072be6
name:ZooKeeperConnection Watcher:127.0.0.1:50268/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2118122 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2118125 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2118129 T3964 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@787388b2
name:ZooKeeperConnection Watcher:127.0.0.1:50268 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2118130 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2118137 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2118140 T3966 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1d405778
name:ZooKeeperConnection Watcher:127.0.0.1:50268 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2118140 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2118140 T3852 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2118151 T3852 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2118158 T3852 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 2118163 T3852 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 2118168 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2118175 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2118178 T3968 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@24a35f88
name:ZooKeeperConnection Watcher:127.0.0.1:50268/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2118178 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2118182 T3852
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 2118188 T3852 oascc.SolrZkClient.makePath makePath:
/live_nodes/node1
[junit4] 2> 2118197 T3852 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 2118200 T3972 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5fe9838e
name:ZooKeeperConnection Watcher:127.0.0.1:50268/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2118201 T3852 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 2118203 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 2118208 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 2118218 T3852 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:50268_solr
[junit4] 2> 2118219 T3852 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 2118225 T3852 oasc.Overseer.start Overseer
(id=92268526173224964-127.0.0.1:50268_solr-n_0000000000) starting
[junit4] 2> 2118229 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 2118239 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 2118247 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 2118257 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 2118267 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 2118275 T3852 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 2118291 T3974 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 2118293 T3973 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 2118298 T3973 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2118299 T3973 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=12 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"12",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 2118300 T3973
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2, shard3, shard4, shard5, shard6,
shard7, shard8, shard9, shard10, shard11, shard12]
[junit4] 2> 2118300 T3973 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard11
[junit4] 2> 2118307 T3975 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2118308 T3969 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2118308 T3970 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2118796 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard11/election
[junit4] 2> 2118824 T3852 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard11
[junit4] 2> 2118838 T3975 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 2118838 T3852 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2118842 T3973 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 2118854 T3852 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50268 50268
[junit4] 2> 2118888 T3956 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:50268 50268
[junit4] 2> 2119129 T3852 oas.SolrTestCaseJ4.tearDown ###Ending
testPlaceholders
[junit4] 2> 2119130 T3973 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:50268_solr
[junit4] 2> 2119133 T3976
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org
[...truncated too long message...]
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:558)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] > at
org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:124)
[junit4] > at
org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:68)
[junit4] > at
org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:54)
[junit4] > at
org.apache.solr.schema.TestCloudSchemaless.doTest(TestCloudSchemaless.java:140)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at
java.net.SocketInputStream.read(SocketInputStream.java:152)
[junit4] > at
java.net.SocketInputStream.read(SocketInputStream.java:122)
[junit4] > at
sun.security.ssl.InputRecord.readFully(InputRecord.java:442)
[junit4] > at
sun.security.ssl.InputRecord.read(InputRecord.java:480)
[junit4] > at
sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:927)
[junit4] > at
sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:884)
[junit4] > at
sun.security.ssl.AppInputStream.read(AppInputStream.java:102)
[junit4] > at
org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
[junit4] > at
org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
[junit4] > at
org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
[junit4] > at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
[junit4] > at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
[junit4] > at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
[junit4] > at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
[junit4] > at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
[junit4] > at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
[junit4] > at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:448)
[junit4] > ... 46 more
[junit4] 2> 3666556 T6960 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.schema.TestCloudSchemaless-3D207A17C93FB1B6-001
[junit4] 2> 290558 T6959 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene45,
sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=fr_FR,
timezone=Australia/South
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=2,threads=1,free=248650320,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestIndexSearcher,
TestReplicationHandler, TermsComponentTest, MigrateRouteKeyTest,
SpellCheckCollatorTest, TestGroupingSearch, DistributedIntervalFacetingTest,
QueryResultKeyTest, DistribCursorPagingTest, TestFieldResource,
AddBlockUpdateTest, RecoveryZkTest, SpellingQueryConverterTest,
TriLevelCompositeIdRoutingTest, HdfsCollectionsAPIDistributedZkTest,
TestStressReorder, TestShortCircuitedRequests,
HdfsWriteToMultipleCollectionsTest, SolrCoreCheckLockOnStartupTest,
TestFastOutputStream, NoCacheHeaderTest, BlockCacheTest,
AlternateDirectoryTest, TestIBSimilarityFactory, TestSolrJ, TestXIncludeConfig,
TestCharFilters, DistributedSuggestComponentTest, SearchHandlerTest,
TestChildDocTransformer, TestManagedStopFilterFactory, QueryEqualityTest,
TestInitQParser, SolrIndexConfigTest, TestSchemaVersionResource,
TestNonDefinedSimilarityFactory, TestSuggestSpellingConverter,
AnalysisAfterCoreReloadTest, TestStressVersions,
SchemaVersionSpecificBehaviorTest, UUIDFieldTest, TestAnalyzedSuggestions,
TestManagedResourceStorage, PreAnalyzedFieldTest, TestOrdValues,
MBeansHandlerTest, SolrIndexSplitterTest, TestPseudoReturnFields,
FastVectorHighlighterTest, TestCodecSupport, JSONWriterTest,
CollectionsAPIDistributedZkTest, HdfsBasicDistributedZkTest, TestBinaryField,
OverseerStatusTest, ResourceLoaderTest, TestReplicationHandlerBackup,
TestQueryUtils, DistributedSpellCheckComponentTest, TestLuceneMatchVersion,
TestNonNRTOpen, DocumentBuilderTest, TestStressUserVersions,
TestPHPSerializedResponseWriter, RequestHandlersTest, FunctionTest,
TestArbitraryIndexDir, SuggesterFSTTest, ChangedSchemaMergeTest,
SolrCmdDistributorTest, CursorPagingTest, LegacyHTMLStripCharFilterTest,
TestSort, TestSolrCoreProperties, UpdateRequestProcessorFactoryTest,
DistributedDebugComponentTest, TestConfigSets, InfoHandlerTest,
SpellCheckComponentTest, TestPerFieldSimilarity, TestConfig, CursorMarkTest,
TestElisionMultitermQuery, BasicDistributedZk2Test, OverseerTest,
PrimitiveFieldTypeTest, TestDistributedGrouping, RequiredFieldsTest,
DefaultValueUpdateProcessorTest, TestSolrXml, TestUtils,
TestComplexPhraseQParserPlugin, TestPhraseSuggestions, SuggesterWFSTTest,
TestSweetSpotSimilarityFactory, TestMaxScoreQueryParser,
AnalyticsMergeStrategyTest, TestFieldTypeResource, IndexSchemaRuntimeFieldTest,
SimpleFacetsTest, TestQueryTypes, UUIDUpdateProcessorFallbackTest,
TestWordDelimiterFilterFactory, UniqFieldsUpdateProcessorFactoryTest,
HighlighterConfigTest, TestSolrQueryParser, TestDistributedSearch,
StressHdfsTest, TestFiltering, TestFastWriter, RangeFacetTest,
CoreAdminRequestStatusTest, TestComponentsName,
AddSchemaFieldsUpdateProcessorFactoryTest, HighlighterMaxOffsetTest,
MultiThreadedOCPTest, SortByFunctionTest, DateFieldTest,
DocExpirationUpdateProcessorFactoryTest, SystemInfoHandlerTest,
SolrInfoMBeanTest, SuggesterTest, SliceStateTest, ReplicationFactorTest,
TestReversedWildcardFilterFactory, TestFreeTextSuggestions,
DistributedExpandComponentTest, DistributedQueryElevationComponentTest,
BadCopyFieldTest, TestInfoStreamLogging, DOMUtilTest, CurrencyFieldXmlFileTest,
TestTrie, TestMergePolicyConfig, TestUpdate,
CollectionsAPIAsyncDistributedZkTest, TestCSVLoader, IndexSchemaTest,
SolrRequestParserTest, DocumentAnalysisRequestHandlerTest,
ChaosMonkeyNothingIsSafeTest, UpdateParamsTest,
FieldAnalysisRequestHandlerTest, ConvertedLegacyTest,
TestSerializedLuceneMatchVersion, AutoCommitTest,
TestCursorMarkWithoutUniqueKey, ClusterStateTest, HttpPartitionTest,
MinimalSchemaTest, CopyFieldTest, SSLMigrationTest, TestCloudInspectUtil,
TestCustomSort, TestShardHandlerFactory,
TestOverriddenPrefixQueryForCustomFieldType, TestSchemaResource,
CSVRequestHandlerTest, TestSolrQueryParserDefaultOperatorResource,
RAMDirectoryFactoryTest, ModifyConfFileTest, SoftAutoCommitTest,
CustomCollectionTest, TestCloudManagedSchemaConcurrent, DocValuesTest,
TestFieldTypeCollectionResource, TestImplicitCoreProperties, HdfsSyncSliceTest,
BJQParserTest, TestOmitPositions, TestRecovery, TestCollapseQParserPlugin,
SuggesterTSTTest, TestValueSourceCache, TestCloudSchemaless]
[junit4] Completed in 291.39s, 1 test, 1 error <<< FAILURES!
[...truncated 751 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:474: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:454: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:496:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1307:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922:
There were test failures: 421 suites, 1761 tests, 2 errors, 44 ignored (17
assumptions)
Total time: 168 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0
-XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
