You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/08/13 18:38:13 UTC
[JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_60-ea-b24) -
Build # 13843 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/13843/
Java: 32bit/jdk1.8.0_60-ea-b24 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerTest.testReplay
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([421120DE5ED5BC15:AD8289AB0E6CD6E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.OverseerTest.testReplay(OverseerTest.java:1157)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
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:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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:54)
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)
Build Log:
[...truncated 10522 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerTest_421120DE5ED5BC15-001/init-core-data-001
[junit4] 2> 759967 INFO (SUITE-OverseerTest-seed#[421120DE5ED5BC15]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 759967 INFO (SUITE-OverseerTest-seed#[421120DE5ED5BC15]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 759967 INFO (SUITE-OverseerTest-seed#[421120DE5ED5BC15]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 759969 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testShardAssignmentBigger
[junit4] 2> 759969 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 759969 INFO (Thread-2206) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 759969 INFO (Thread-2206) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 760069 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.ZkTestServer start zk server on port:54828
[junit4] 2> 760070 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760070 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760071 INFO (zkCallback-663-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e8f15 name:ZooKeeperConnection Watcher:127.0.0.1:54828 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760072 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760072 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760072 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760073 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760073 INFO (zkCallback-664-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cd620b name:ZooKeeperConnection Watcher:127.0.0.1:54828 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760073 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760074 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760074 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 760075 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760075 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760076 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1187382 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760076 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760076 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760076 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 760077 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 760077 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 760078 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 760078 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 760078 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760079 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760079 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e53f93 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760079 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760079 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760079 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 760080 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 760081 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 760081 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 760081 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94337960635072515-127.0.0.1:54828_solr-n_0000000000
[junit4] 2> 760082 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54828_solr
[junit4] 2> 760082 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 760082 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.Overseer Overseer (id=94337960635072515-127.0.0.1:54828_solr-n_0000000000) starting
[junit4] 2> 760082 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 760083 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 760084 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 760084 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 760085 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 760085 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 760086 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 760086 INFO (OverseerCollectionProcessor-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.OverseerProcessor Process current queue of overseer operations
[junit4] 2> 760086 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760086 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 760087 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760088 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760088 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760089 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@172a7f9 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760089 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760089 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760089 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760090 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node0
[junit4] 2> 760090 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 760090 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 760090 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 760090 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760092 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760092 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@878f58 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760092 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760093 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760093 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760094 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node1
[junit4] 2> 760094 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 760094 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 760094 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 760094 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 760094 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760095 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760095 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@115c07a name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760095 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760096 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760096 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760096 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node2
[junit4] 2> 760097 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 760097 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 760097 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760097 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 760097 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 760097 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 760097 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760098 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e04d0c name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760098 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760098 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760099 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760099 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node3
[junit4] 2> 760100 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760100 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760100 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760100 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760100 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760101 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760100 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 760101 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760102 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11f941a name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760102 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760102 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760103 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760103 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node4
[junit4] 2> 760103 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760103 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760104 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760104 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760103 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760103 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760103 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 760104 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760105 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760105 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8d4018 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760105 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760106 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760106 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760107 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node5
[junit4] 2> 760107 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760107 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760107 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760107 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4] 2> 760108 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a4140d name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760108 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760109 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760109 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760110 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node6
[junit4] 2> 760110 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760110 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760110 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 6)
[junit4] 2> 760111 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760112 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19d602d name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760112 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760112 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760112 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760113 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node7
[junit4] 2> 760113 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760113 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760114 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760113 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760113 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760113 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 7)
[junit4] 2> 760114 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760115 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11edac9 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760115 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760115 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760116 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760116 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node8
[junit4] 2> 760117 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760117 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 8)
[junit4] 2> 760117 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760118 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e70169 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760118 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760119 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760119 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760120 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node9
[junit4] 2> 760120 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760121 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760121 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760121 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760120 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760121 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760121 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 9)
[junit4] 2> 760122 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760123 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@43aaad name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760124 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760124 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760125 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760125 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node10
[junit4] 2> 760126 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760127 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760126 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760127 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760126 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760126 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760127 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 10)
[junit4] 2> 760128 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@dbff56 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760128 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760128 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760129 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760129 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node11
[junit4] 2> 760130 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 11)
[junit4] 2> 760130 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760132 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760132 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61d140 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760133 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760133 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760133 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760134 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node12
[junit4] 2> 760134 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760135 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760135 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760134 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 12)
[junit4] 2> 760136 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760137 INFO (zkCallback-681-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@dfc834 name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760137 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760137 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760138 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760138 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node13
[junit4] 2> 760139 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-681-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760140 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 760139 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760139 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 13)
[junit4] 2> 760147 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760147 INFO (zkCallback-682-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@182154a name:ZooKeeperConnection Watcher:127.0.0.1:54828/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 760148 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760148 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 760149 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 760149 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node14
[junit4] 2> 760150 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-682-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-681-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760150 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 14)
[junit4] 2> 760152 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760153 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node0",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node0/solr/"} current state version: 0
[junit4] 2> 760153 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node0",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node0/solr/"}
[junit4] 2> 760153 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 760153 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760154 INFO (zkCallback-676-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-672-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-678-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-679-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760155 INFO (zkCallback-669-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-677-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-675-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-674-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-682-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-680-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-665-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-668-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-670-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-673-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760154 INFO (zkCallback-681-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 15)
[junit4] 2> 760156 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 14, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node2",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node2/solr/"} current state version: 1
[junit4] 2> 760157 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node2",
[junit4] 2> "core":"core2",
[junit4] 2> "core_node_name":"node2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node2/solr/"}
[junit4] 2> 760157 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760157 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760157 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760157 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 14, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 1
[junit4] 2> 760158 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 760158 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760158 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760158 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 12, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node3",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node3/solr/"} current state version: 1
[junit4] 2> 760158 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node3",
[junit4] 2> "core":"core3",
[junit4] 2> "core_node_name":"node3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node3/solr/"}
[junit4] 2> 760159 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760159 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760159 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760159 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 12, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node6",
[junit4] 2> "core":"core6",
[junit4] 2> "core_node_name":"node6",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node6/solr/"} current state version: 1
[junit4] 2> 760160 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node6",
[junit4] 2> "core":"core6",
[junit4] 2> "core_node_name":"node6",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node6/solr/"}
[junit4] 2> 760160 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760160 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760160 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 10, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node4",
[junit4] 2> "core":"core4",
[junit4] 2> "core_node_name":"node4",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node4/solr/"} current state version: 1
[junit4] 2> 760161 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node4",
[junit4] 2> "core":"core4",
[junit4] 2> "core_node_name":"node4",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node4/solr/"}
[junit4] 2> 760161 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760161 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760161 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760161 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 10, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node5",
[junit4] 2> "core":"core5",
[junit4] 2> "core_node_name":"node5",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node5/solr/"} current state version: 1
[junit4] 2> 760161 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node5",
[junit4] 2> "core":"core5",
[junit4] 2> "core_node_name":"node5",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node5/solr/"}
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 8, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node7",
[junit4] 2> "core":"core7",
[junit4] 2> "core_node_name":"node7",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node7/solr/"} current state version: 1
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node7",
[junit4] 2> "core":"core7",
[junit4] 2> "core_node_name":"node7",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node7/solr/"}
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760162 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760163 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760163 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 8, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node11",
[junit4] 2> "core":"core11",
[junit4] 2> "core_node_name":"node11",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node11/solr/"} current state version: 1
[junit4] 2> 760163 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node11",
[junit4] 2> "core":"core11",
[junit4] 2> "core_node_name":"node11",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node11/solr/"}
[junit4] 2> 760163 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760163 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760164 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 6, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node12",
[junit4] 2> "core":"core12",
[junit4] 2> "core_node_name":"node12",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node12/solr/"} current state version: 1
[junit4] 2> 760164 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node12",
[junit4] 2> "core":"core12",
[junit4] 2> "core_node_name":"node12",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node12/solr/"}
[junit4] 2> 760164 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760164 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760165 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 760165 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 6, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node10",
[junit4] 2> "core":"core10",
[junit4] 2> "core_node_name":"node10",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node10/solr/"} current state version: 1
[junit4] 2> 760165 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node10",
[junit4] 2> "core":"core10",
[junit4] 2> "core_node_name":"node10",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node10/solr/"}
[junit4] 2> 760165 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760165 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 760166 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node9",
[junit4] 2> "core":"core9",
[junit4] 2> "core_node_name":"node9",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node9/solr/"} current state version: 1
[junit4] 2> 760166 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node9",
[junit4] 2> "core":"core9",
[junit4] 2> "core_node_name":"node9",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://node9/solr/"}
[junit4] 2> 760166 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 760166 INFO (OverseerStateUpdate-94337960635072515-127.0.0.1:54828_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 760167 INFO (zkCallback-666-thread-1) [ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on p
[...truncated too long message...]
41986-127.0.0.1:38018_solr-n_0000000001
[junit4] 2> 829371 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38018_solr
[junit4] 2> 829371 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 829372 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.Overseer Overseer (id=94337965142441986-127.0.0.1:38018_solr-n_0000000001) starting
[junit4] 2> 829374 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 829374 INFO (OverseerCollectionProcessor-94337965142441986-127.0.0.1:38018_solr-n_0000000001) [ ] o.a.s.c.OverseerProcessor Process current queue of overseer operations
[junit4] 2> 829374 INFO (OverseerStateUpdate-94337965142441986-127.0.0.1:38018_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 829374 INFO (zkCallback-801-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 829374 INFO (zkCallback-802-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 829374 WARN (zkCallback-802-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 830875 WARN (OverseerStateUpdate-94337965142441986-127.0.0.1:38018_solr-n_0000000001) [ ] o.a.s.c.Overseer Solr cannot talk to ZK, exiting Overseer work queue loop
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /live_nodes
[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$6.execute(SolrZkClient.java:336)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:333)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:333)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.refreshLiveNodes(ZkStateReader.java:569)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.updateClusterState(ZkStateReader.java:262)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:154)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 830876 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38018 38018
[junit4] 2> 830875 WARN (OverseerCollectionProcessor-94337965142441986-127.0.0.1:38018_solr-n_0000000001) [ ] o.a.s.c.OverseerProcessor Overseer cannot talk to ZK
[junit4] 2> 830876 INFO (OverseerStateUpdate-94337965142441986-127.0.0.1:38018_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38018_solr
[junit4] 2> 830910 INFO (Thread-2216) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38018 38018
[junit4] 2> 830911 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> 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$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:300)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:261)
[junit4] 2> 830911 WARN (Thread-2216) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2>
[junit4] 2> 830912 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerStatsReset
[junit4] 2> 830912 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[421120DE5ED5BC15]) [ ] o.a.s.c.Overseer Overseer (id=94337965142441986-127.0.0.1:38018_solr-n_0000000001) closing
[junit4] 2> 833913 INFO (SUITE-OverseerTest-seed#[421120DE5ED5BC15]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.OverseerTest_421120DE5ED5BC15-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {}, docValues:{}, sim=DefaultSimilarity, locale=ar_TN, timezone=US/Hawaii
[junit4] 2> NOTE: Linux 3.16.0-44-generic i386/Oracle Corporation 1.8.0_60-ea (32-bit)/cpus=12,threads=1,free=70706248,total=399245312
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrCloudWithKerberosAlt, TestLMJelinekMercerSimilarityFactory, CurrencyFieldOpenExchangeTest, TestBlendedInfixSuggestions, ZkStateWriterTest, TestSolrDeletionPolicy2, TestSortByMinMaxFunction, TestPivotHelperCode, TestSolrCoreProperties, TestFuzzyAnalyzedSuggestions, TestStressUserVersions, JSONWriterTest, TestValueSourceCache, TestIntervalFaceting, DistribDocExpirationUpdateProcessorTest, SecurityConfHandlerTest, TestOmitPositions, TestIndexSearcher, UpdateParamsTest, SolrCloudExampleTest, DistributedFacetPivotWhiteBoxTest, MultiThreadedOCPTest, CdcrRequestHandlerTest, TestStandardQParsers, TestFastWriter, DistributedTermsComponentTest, SolrXmlInZkTest, TestNRTOpen, TestObjectReleaseTracker, TestDistributedMissingSort, TestRestoreCore, TestAnalyzedSuggestions, TestHashPartitioner, StatelessScriptUpdateProcessorFactoryTest, TestExpandComponent, TestElisionMultitermQuery, ShardRoutingCustomTest, BlockDirectoryTest, TestSearchPerf, CopyFieldTest, EchoParamsTest, JsonLoaderTest, TestLRUCache, TestCopyFieldCollectionResource, BadComponentTest, TestSolr4Spatial2, LukeRequestHandlerTest, DistributedExpandComponentTest, ConvertedLegacyTest, DistributedMLTComponentTest, CachingDirectoryFactoryTest, AtomicUpdatesTest, ZkCLITest, ZkControllerTest, AnalyticsQueryTest, TestImplicitCoreProperties, ReturnFieldsTest, TestLMDirichletSimilarityFactory, TestDefaultStatsCache, MigrateRouteKeyTest, TestCoreDiscovery, AsyncCallRequestStatusResponseTest, TestDocBasedVersionConstraints, TestFreeTextSuggestions, HLLUtilTest, TestRTGBase, SystemInfoHandlerTest, TestSuggestSpellingConverter, TestInitQParser, TestFiltering, RemoteQueryErrorTest, TestSolrXml, DistanceFunctionTest, TestManagedSynonymFilterFactory, UUIDUpdateProcessorFallbackTest, SolrPluginUtilsTest, ConcurrentDeleteAndCreateCollectionTest, DistribCursorPagingTest, CollectionsAPIAsyncDistributedZkTest, TestLFUCache, TestSchemaManager, SpellingQueryConverterTest, FieldMutatingUpdateProcessorTest, TestSolrConfigHandler, DirectSolrConnectionTest, TestConfigSets, TestSolrConfigHandlerConcurrent, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest]
[junit4] Completed [276/540] on J1 in 73.96s, 11 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 830 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:514: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1429: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:984: There were test failures: 540 suites, 2128 tests, 1 failure, 68 ignored (32 assumptions)
Total time: 46 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any