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 2016/06/03 17:33:28 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_92) - Build # 227
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/227/
Java: 32bit/jdk1.8.0_92 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Stack Trace:
java.util.concurrent.TimeoutException
at __randomizedtesting.SeedInfo.seed([D89814F7EAB8386F:A706A37283DA15E5]:0)
at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12186 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.overseer.ZkStateReaderTest_D89814F7EAB8386F-001\init-core-data-001
[junit4] 2> 2693995 INFO (SUITE-ZkStateReaderTest-seed#[D89814F7EAB8386F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 2694003 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 2694010 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2694010 INFO (Thread-7438) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2694010 INFO (Thread-7438) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2694110 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51225
[junit4] 2> 2694110 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694112 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694123 INFO (zkCallback-3673-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@185b56e name:ZooKeeperConnection Watcher:127.0.0.1:51225 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694123 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694123 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694138 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694144 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694148 INFO (zkCallback-3674-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13cb71e name:ZooKeeperConnection Watcher:127.0.0.1:51225 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694150 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2694154 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174db5330001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2694157 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694163 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694168 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9d2816 name:ZooKeeperConnection Watcher:127.0.0.1:51225/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694168 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694168 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694173 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2694181 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2694186 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2694189 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2694195 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2694200 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2694216 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2694218 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2694223 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2694223 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2694231 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2694233 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2694236 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2694237 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2694246 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51225 51225
[junit4] 2> 2694251 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174db5330002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2694288 INFO (Thread-7438) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51225 51225
[junit4] 2> 2694296 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 2694300 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 2694301 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2694302 INFO (Thread-7439) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2694302 INFO (Thread-7439) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2694402 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51237
[junit4] 2> 2694402 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694404 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694416 INFO (zkCallback-3677-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1dde49 name:ZooKeeperConnection Watcher:127.0.0.1:51237 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694417 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694418 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694419 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694422 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694424 INFO (zkCallback-3678-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d977d2 name:ZooKeeperConnection Watcher:127.0.0.1:51237 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694424 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694425 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694425 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2694433 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174db6580001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2694434 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694436 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694445 INFO (zkCallback-3679-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6575f7 name:ZooKeeperConnection Watcher:127.0.0.1:51237/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694445 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694445 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694447 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2694454 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2694463 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2694469 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2694481 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2694490 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2694492 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2694494 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2694498 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2694502 INFO (zkCallback-3679-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2694505 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2694508 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2694510 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174db6580002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2694514 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51237 51237
[junit4] 2> 2694515 INFO (Thread-7439) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51237 51237
[junit4] 2> 2694521 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 2694532 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 2694534 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2694534 INFO (Thread-7440) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2694535 INFO (Thread-7440) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2694635 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51249
[junit4] 2> 2694636 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694639 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694646 INFO (zkCallback-3681-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@168fae0 name:ZooKeeperConnection Watcher:127.0.0.1:51249 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694647 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694647 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694650 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694651 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174db7410000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2694651 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694656 INFO (zkCallback-3682-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@144a97 name:ZooKeeperConnection Watcher:127.0.0.1:51249 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2694665 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2694666 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2694669 INFO (zkCallback-3683-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15f1f4 name:ZooKeeperConnection Watcher:127.0.0.1:51249/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694669 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2694670 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2694671 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2694674 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2694679 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2694684 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2694687 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2694691 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2694695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2694698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2694704 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2694704 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2694707 INFO (zkCallback-3683-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2694710 INFO (zkCallback-3683-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: [0])
[junit4] 2> 2694715 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2694717 INFO (zkCallback-3683-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2694720 INFO (zkCallback-3683-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2694722 INFO (zkCallback-3683-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
[junit4] 2> 2724726 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51249 51249
[junit4] 2> 2724730 INFO (Thread-7440) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51249 51249
[junit4] 2> 2724730 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=D89814F7EAB8386F -Dtests.slow=true -Dtests.locale=mk -Dtests.timezone=America/Shiprock -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 30.2s J1 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException
[junit4] > at __randomizedtesting.SeedInfo.seed([D89814F7EAB8386F:A706A37283DA15E5]:0)
[junit4] > at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2724736 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2724738 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2724738 INFO (Thread-7441) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2724738 INFO (Thread-7441) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2724838 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51398
[junit4] 2> 2724838 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2724839 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2724842 INFO (zkCallback-3685-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6be231 name:ZooKeeperConnection Watcher:127.0.0.1:51398 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2724843 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2724843 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2724844 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2724846 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2724847 INFO (zkCallback-3686-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@55d191 name:ZooKeeperConnection Watcher:127.0.0.1:51398 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2724847 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2724847 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2724847 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2724850 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2724855 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2724857 INFO (zkCallback-3687-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19ae0df name:ZooKeeperConnection Watcher:127.0.0.1:51398/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2724858 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2724858 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2724858 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2724859 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2724861 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2724862 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2724864 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2724868 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2724869 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2724869 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2724872 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2724872 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2724874 INFO (zkCallback-3687-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2724875 INFO (zkCallback-3687-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: [0])
[junit4] 2> 2724877 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2724878 INFO (zkCallback-3687-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2724878 INFO (zkCallback-3687-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2724878 INFO (zkCallback-3687-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
[junit4] 2> 2724883 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51398 51398
[junit4] 2> 2724886 INFO (Thread-7441) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51398 51398
[junit4] 2> 2724886 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2724891 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2724892 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2724893 INFO (Thread-7442) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2724893 INFO (Thread-7442) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2724992 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51410
[junit4] 2> 2724992 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2724993 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2724998 INFO (zkCallback-3689-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@80ed14 name:ZooKeeperConnection Watcher:127.0.0.1:51410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2724998 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2724998 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2724999 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174e2dd30000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2725000 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2725002 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2725003 INFO (zkCallback-3690-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f61b13 name:ZooKeeperConnection Watcher:127.0.0.1:51410 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2725003 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2725004 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2725004 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2725008 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155174e2dd30001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2725009 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2725012 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2725012 INFO (zkCallback-3691-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14a0de0 name:ZooKeeperConnection Watcher:127.0.0.1:51410/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2725013 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2725013 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2725013 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2725017 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2725018 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2725020 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2725022 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2725023 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2725024 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2725025 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2725027 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2725028 INFO (zkCallback-3691-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2725030 INFO (zkCallback-3691-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: [0])
[junit4] 2> 2725031 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2725032 INFO (zkCallback-3691-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: [0])
[junit4] 2> 2725038 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51410 51410
[junit4] 2> 2725040 INFO (Thread-7442) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51410 51410
[junit4] 2> 2725042 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2725046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2725047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2725048 INFO (Thread-7443) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2725048 INFO (Thread-7443) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2725148 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51422
[junit4] 2> 2725148 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2725149 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2725152 INFO (zkCallback-3693-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9c0489 name:ZooKeeperConnection Watcher:127.0.0.1:51422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2725152 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2725154 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2725154 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2725157 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2725158 INFO (zkCallback-3694-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@383a32 name:ZooKeeperConnection Watcher:127.0.0.1:51422 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2725159 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2725159 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2725159 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2725167 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2725169 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2725170 INFO (zkCallback-3695-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f3871d name:ZooKeeperConnection Watcher:127.0.0.1:51422/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2725171 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2725171 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2725172 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2725174 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2725175 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2725177 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2725179 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2725180 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2725181 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2725181 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2725183 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2725184 INFO (zkCallback-3695-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2725185 INFO (zkCallback-3695-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: [0])
[junit4] 2> 2725187 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2725188 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2725188 INFO (zkCallback-3695-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 2725189 INFO (zkCallback-3695-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: [0])
[junit4] 2> 2725190 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2725192 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51422 51422
[junit4] 2> 2725196 INFO (Thread-7443) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51422 51422
[junit4] 2> 2725198 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[D89814F7EAB8386F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2725200 INFO (SUITE-ZkStateReaderTest-seed#[D89814F7EAB8386F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.overseer.ZkStateReaderTest_D89814F7EAB8386F-001
[junit4] 2> Jun 03, 2016 5:27:27 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 7 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=330, maxMBSortInHeap=5.111521161362539, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=mk, timezone=America/Shiprock
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=125823656,total=438829056
[junit4] 2> NOTE: All tests run in this JVM: [TestExactSharedStatsCache, TestSuggestSpellingConverter, TestCSVResponseWriter, MultiTermTest, CollectionsAPIDistributedZkTest, TestLMJelinekMercerSimilarityFactory, TestIndexingPerformance, QueryParsingTest, ConvertedLegacyTest, TestSSLRandomization, TestSlowCompositeReaderWrapper, BigEndianAscendingWordSerializerTest, AutoCommitTest, SolrCoreCheckLockOnStartupTest, TlogReplayBufferedWhileIndexingTest, TestRTGBase, TestSchemaVersionResource, TestFiltering, TestSolrQueryParser, CurrencyFieldOpenExchangeTest, TestSolrQueryParserDefaultOperatorResource, TestOrdValues, TestSolrQueryResponse, TestStressRecovery, TestRecovery, CreateCollectionCleanupTest, TestNonDefinedSimilarityFactory, TestFieldCacheWithThreads, HighlighterConfigTest, ExplicitHLLTest, TestFastWriter, TestCollationField, TestTrie, TestReversedWildcardFilterFactory, UniqFieldsUpdateProcessorFactoryTest, TestRestManager, TestReloadAndDeleteDocs, TestCharFilters, CloneFieldUpdateProcessorFactoryTest, DistributedTermsComponentTest, TestManagedSchema, TermVectorComponentTest, TestSimpleTrackingShardHandler, JavabinLoaderTest, CheckHdfsIndexTest, EchoParamsTest, TestGraphTermsQParserPlugin, DistribDocExpirationUpdateProcessorTest, OverseerTaskQueueTest, TestBM25SimilarityFactory, TestSchemaManager, TestStandardQParsers, TestStressCloudBlindAtomicUpdates, TestRandomFaceting, RequestHandlersTest, TestQuerySenderListener, DebugComponentTest, FieldMutatingUpdateProcessorTest, TestOnReconnectListenerSupport, TestFieldTypeResource, TestOverriddenPrefixQueryForCustomFieldType, TestCloudManagedSchema, SmileWriterTest, TestSha256AuthenticationProvider, TestManagedSynonymFilterFactory, FileUtilsTest, TestAtomicUpdateErrorCases, TestConfigSetImmutable, IndexSchemaRuntimeFieldTest, TestExclusionRuleCollectionAccess, TestDistributedGrouping, NotRequiredUniqueKeyTest, ConnectionReuseTest, TestUniqueKeyFieldResource, DistributedFacetPivotWhiteBoxTest, TestFreeTextSuggestions, RuleEngineTest, DistributedMLTComponentTest, AnalyticsQueryTest, TestExceedMaxTermLength, AsyncCallRequestStatusResponseTest, StatsComponentTest, TestSchemaResource, SpellCheckComponentTest, ZkNodePropsTest, TestBadConfig, TestFastOutputStream, TestFieldCacheSanityChecker, BasicDistributedZk2Test, TestReloadDeadlock, HdfsChaosMonkeySafeLeaderTest, RAMDirectoryFactoryTest, AnalysisErrorHandlingTest, TestAuthenticationFramework, DateMathParserTest, ConfigSetsAPITest, TestPKIAuthenticationPlugin, TestValueSourceCache, SynonymTokenizerTest, OverseerRolesTest, TestSolrConfigHandler, CSVRequestHandlerTest, DistributedIntervalFacetingTest, ExitableDirectoryReaderTest, TestDefaultSearchFieldResource, TestSolrConfigHandlerCloud, CollectionReloadTest, PluginInfoTest, HdfsTlogReplayBufferedWhileIndexingTest, TestFieldCacheSortRandom, LeaderFailoverAfterPartitionTest, SolrPluginUtilsTest, SortByFunctionTest, ChaosMonkeySafeLeaderTest, HLLSerializationTest, CachingDirectoryFactoryTest, TestFieldResource, TestPHPSerializedResponseWriter, TestScoreJoinQPScore, TestDistributedStatsComponentCardinality, FullSolrCloudDistribCmdsTest, SolrIndexSplitterTest, TestRTimerTree, DistanceUnitsTest, TestMissingGroups, TestInitParams, TestSolrXml, SearchHandlerTest, HdfsNNFailoverTest, TestChildDocTransformer, GraphQueryTest, BufferStoreTest, TestReload, TestNumericTerms32, TestZkChroot, TestRebalanceLeaders, DistributedDebugComponentTest, TestSystemIdResolver, TestRandomCollapseQParserPlugin, HdfsSyncSliceTest, TestBinaryResponseWriter, CdcrVersionReplicationTest, TestReRankQParserPlugin, TestRawResponseWriter, CacheHeaderTest, HdfsRecoveryZkTest, TestUseDocValuesAsStored2, SSLMigrationTest, DistributedFacetPivotLargeTest, PKIAuthenticationIntegrationTest, TestPerFieldSimilarityWithDefaultOverride, TestDefaultStatsCache, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ZkSolrClientTest, TestFaceting, TestHashPartitioner, TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, HardAutoCommitTest, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SolrCmdDistributorTest, BadIndexSchemaTest, TestSort, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest, SimplePostToolTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, SuggesterWFSTTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestWordDelimiterFilterFactory, QueryEqualityTest, DefaultValueUpdateProcessorTest, DocValuesMultiTest, DistanceFunctionTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, TestWriterPerf, TestQueryTypes, TestOmitPositions, TermsComponentTest, MoreLikeThisHandlerTest, LoggingHandlerTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestAnalyzedSuggestions, TestComponentsName, SOLR749Test, TestSolrIndexConfig, CopyFieldTest, ResponseLogComponentTest, TestSolrDeletionPolicy2, SampleTest, TestBinaryField, NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory, TestJmxMonitoredMap, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, PrimUtilsTest, DateFieldTest, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest, TestLRUCache, SliceStateTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestTolerantSearch, TestJettySolrRunner, AliasIntegrationTest, BaseCdcrDistributedZkTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CleanupOldIndexTest, CollectionStateFormat2Test, CollectionsAPIAsyncDistributedZkTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, DeleteStatusTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, HttpPartitionTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, TestCloudBackupRestore, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPivotFacet, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestLockTree, TestMiniSolrCloudCluster, TestRequestForwarding, TestRequestStatusCollectionAPI, TestSolrCloudWithKerberosAlt, TriLevelCompositeIdRoutingTest, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsCollectionsAPIDistributedZkTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, ZkStateReaderTest]
[junit4] Completed [503/613 (1!)] on J1 in 31.51s, 6 tests, 1 error <<< FAILURES!
[...truncated 362 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\common-build.xml:531: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1427: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:984: There were test failures: 613 suites (10 ignored), 2565 tests, 1 error, 126 ignored (82 assumptions) [seed: D89814F7EAB8386F]
Total time: 76 minutes 7 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
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_92) - Build # 228
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/228/
Java: 32bit/jdk1.8.0_92 -client -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Stack Trace:
java.util.concurrent.TimeoutException
at __randomizedtesting.SeedInfo.seed([76EA0A5A9CBB0D95:974BDDFF5D9201F]:0)
at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11148 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.overseer.ZkStateReaderTest_76EA0A5A9CBB0D95-001\init-core-data-001
[junit4] 2> 785790 INFO (SUITE-ZkStateReaderTest-seed#[76EA0A5A9CBB0D95]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 785793 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 785796 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 785799 INFO (Thread-2334) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 785799 INFO (Thread-2334) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 785899 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64463
[junit4] 2> 785899 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 785900 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 785910 INFO (zkCallback-1231-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19f43b2 name:ZooKeeperConnection Watcher:127.0.0.1:64463 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 785911 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 785911 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 785914 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 785916 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 785917 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 785921 INFO (zkCallback-1232-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cdbb6 name:ZooKeeperConnection Watcher:127.0.0.1:64463 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 785921 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 785922 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 785922 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 785928 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 785933 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 785935 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 785938 INFO (zkCallback-1233-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@983310 name:ZooKeeperConnection Watcher:127.0.0.1:64463/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 785938 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 785939 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 785940 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 785946 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 785953 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 785960 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 785964 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 785968 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 785970 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 785973 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 785982 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 785982 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 785987 INFO (zkCallback-1233-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 785992 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 785995 INFO (zkCallback-1233-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 785998 INFO (zkCallback-1233-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 786001 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6cd90002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 786014 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64463 64463
[junit4] 2> 786018 INFO (Thread-2334) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64463 64463
[junit4] 2> 786022 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 786032 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 786034 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 786036 INFO (Thread-2335) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 786036 INFO (Thread-2335) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 786136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64475
[junit4] 2> 786136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786138 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786149 INFO (zkCallback-1235-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9388ee name:ZooKeeperConnection Watcher:127.0.0.1:64475 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786149 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786150 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786154 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786155 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786168 INFO (zkCallback-1236-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69af29 name:ZooKeeperConnection Watcher:127.0.0.1:64475 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786168 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786168 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786168 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 786180 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786181 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786198 INFO (zkCallback-1237-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@110bde0 name:ZooKeeperConnection Watcher:127.0.0.1:64475/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786198 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786198 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786201 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 786205 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 786211 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 786216 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 786222 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 786228 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 786230 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 786235 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 786241 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 786241 INFO (zkCallback-1237-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 786247 INFO (zkCallback-1237-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: [0])
[junit4] 2> 786254 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 786257 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 786259 INFO (zkCallback-1237-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 786260 INFO (zkCallback-1237-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: [0])
[junit4] 2> 786264 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 786266 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a6dc50002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 786272 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64475 64475
[junit4] 2> 786277 INFO (Thread-2335) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64475 64475
[junit4] 2> 786285 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 786292 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 786294 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 786295 INFO (Thread-2336) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 786295 INFO (Thread-2336) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 786395 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64487
[junit4] 2> 786395 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786396 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786434 INFO (zkCallback-1239-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@100d0e3 name:ZooKeeperConnection Watcher:127.0.0.1:64487 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786435 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786435 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786468 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786469 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786475 INFO (zkCallback-1240-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b90cc9 name:ZooKeeperConnection Watcher:127.0.0.1:64487 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786475 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786475 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786475 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 786491 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786502 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786522 INFO (zkCallback-1241-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f3646 name:ZooKeeperConnection Watcher:127.0.0.1:64487/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786522 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786522 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786522 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 786541 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 786545 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 786548 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 786556 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 786560 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 786561 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 786564 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 786567 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 786590 INFO (zkCallback-1241-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 786594 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 786596 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 786605 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64487 64487
[junit4] 2> 786609 INFO (Thread-2336) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64487 64487
[junit4] 2> 786610 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 786619 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 786621 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 786622 INFO (Thread-2337) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 786622 INFO (Thread-2337) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 786721 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64499
[junit4] 2> 786721 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786721 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786737 INFO (zkCallback-1243-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d0011 name:ZooKeeperConnection Watcher:127.0.0.1:64499 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786737 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786738 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786741 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a70140000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 786743 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786745 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786750 INFO (zkCallback-1244-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@db667 name:ZooKeeperConnection Watcher:127.0.0.1:64499 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786750 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786751 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786751 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 786797 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 786798 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 786803 INFO (zkCallback-1245-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d2905 name:ZooKeeperConnection Watcher:127.0.0.1:64499/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 786803 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 786804 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 786804 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 786812 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 786817 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 786819 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 786831 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 786840 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 786842 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 786845 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 786852 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 786852 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 786856 INFO (zkCallback-1245-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 786860 INFO (zkCallback-1245-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: [0])
[junit4] 2> 786869 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 786870 INFO (zkCallback-1245-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 786877 INFO (zkCallback-1245-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 786878 INFO (zkCallback-1245-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
[junit4] 2> 786892 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64499 64499
[junit4] 2> 786895 INFO (Thread-2337) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64499 64499
[junit4] 2> 786897 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 786903 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 786905 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 786907 INFO (Thread-2338) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 786907 INFO (Thread-2338) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 787005 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64511
[junit4] 2> 787005 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 787006 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 787023 INFO (zkCallback-1247-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@199315c name:ZooKeeperConnection Watcher:127.0.0.1:64511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 787023 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 787023 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 787033 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 787039 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 787049 INFO (zkCallback-1248-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ad5a05 name:ZooKeeperConnection Watcher:127.0.0.1:64511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 787049 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 787053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 787053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 787077 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 787084 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 787084 INFO (zkCallback-1249-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b3f935 name:ZooKeeperConnection Watcher:127.0.0.1:64511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 787084 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 787084 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 787084 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 787101 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 787105 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 787117 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 787129 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 787153 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 787171 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 787173 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 787176 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 787176 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 787182 INFO (zkCallback-1249-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 787189 INFO (zkCallback-1249-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: [0])
[junit4] 2> 787192 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 787193 INFO (zkCallback-1249-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 787194 INFO (zkCallback-1249-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 787196 INFO (zkCallback-1249-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0])
[junit4] 2> 817200 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191a712d0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 817203 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64511 64511
[junit4] 2> 817207 INFO (Thread-2338) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64511 64511
[junit4] 2> 817209 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=76EA0A5A9CBB0D95 -Dtests.slow=true -Dtests.locale=is -Dtests.timezone=Australia/Lindeman -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 30.3s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException
[junit4] > at __randomizedtesting.SeedInfo.seed([76EA0A5A9CBB0D95:974BDDFF5D9201F]:0)
[junit4] > at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 817219 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 817224 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 817224 INFO (Thread-2339) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 817224 INFO (Thread-2339) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 817325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer start zk server on port:64631
[junit4] 2> 817325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 817327 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 817336 INFO (zkCallback-1251-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b7148b name:ZooKeeperConnection Watcher:127.0.0.1:64631 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 817336 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 817337 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 817340 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 817342 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 817349 INFO (zkCallback-1252-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1429f22 name:ZooKeeperConnection Watcher:127.0.0.1:64631 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 817349 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 817349 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 817349 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 817354 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x155191ae7980001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 817355 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 817357 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 817362 INFO (zkCallback-1253-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a90fa2 name:ZooKeeperConnection Watcher:127.0.0.1:64631/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 817362 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 817363 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 817364 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 817370 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 817373 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 817377 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 817381 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 817384 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 817386 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 817388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 817392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 817400 INFO (zkCallback-1253-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 817403 INFO (zkCallback-1253-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: [0])
[junit4] 2> 817410 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 817413 INFO (zkCallback-1253-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: [0])
[junit4] 2> 817429 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64631 64631
[junit4] 2> 817432 INFO (Thread-2339) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:64631 64631
[junit4] 2> 817444 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EA0A5A9CBB0D95]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 817446 INFO (SUITE-ZkStateReaderTest-seed#[76EA0A5A9CBB0D95]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.overseer.ZkStateReaderTest_76EA0A5A9CBB0D95-001
[junit4] 2> Jun 04, 2016 1:50:41 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 4 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=808, maxMBSortInHeap=6.795521191627375, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=is, timezone=Australia/Lindeman
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=89746224,total=292290560
[junit4] 2> NOTE: All tests run in this JVM: [TestCodecSupport, TestFieldCacheReopen, BadComponentTest, TestTrackingShardHandlerFactory, SliceStateTest, DistribCursorPagingTest, PolyFieldTest, CircularListTest, TestNumericTerms64, TestMergePolicyConfig, AssignTest, TestRuleBasedAuthorizationPlugin, ActionThrottleTest, PrimUtilsTest, TestReplicaProperties, TestDocSet, TestSortByMinMaxFunction, TestSerializedLuceneMatchVersion, MBeansHandlerTest, TestTolerantSearch, RulesTest, TestArbitraryIndexDir, TestMaxScoreQueryParser, TestRecoveryHdfs, TestFastLRUCache, TestNRTOpen, ClusterStateTest, CoreAdminRequestStatusTest, SimpleFacetsTest, TestPerFieldSimilarityClassic, ResponseHeaderTest, SoftAutoCommitTest, ScriptEngineTest, SolrRequestParserTest, RankQueryTest, TestSolrCloudWithKerberosAlt, ConnectionManagerTest, CdcrUpdateLogTest, TestDistribIDF, StatelessScriptUpdateProcessorFactoryTest, BasicAuthIntegrationTest, TestMinMaxOnMultiValuedField, RequiredFieldsTest, SampleTest, TestConfigSetsAPIExclusivity, IndexSchemaTest, TestManagedSchemaAPI, SystemInfoHandlerTest, PreAnalyzedFieldTest, SharedFSAutoReplicaFailoverTest, TestShortCircuitedRequests, HighlighterTest, TestLuceneMatchVersion, SolrTestCaseJ4Test, MigrateRouteKeyTest, TestSolrConfigHandlerConcurrent, SimpleMLTQParserTest, TestSchemaSimilarityResource, QueryEqualityTest, TestLockTree, TestReqParamsAPI, TestInfoStreamLogging, HdfsChaosMonkeyNothingIsSafeTest, AnalyticsMergeStrategyTest, TestDocTermOrds, DeleteLastCustomShardedReplicaTest, HdfsDirectoryFactoryTest, DistributedFacetPivotSmallTest, SimplePostToolTest, TermsComponentTest, BlockJoinFacetDistribTest, CloudExitableDirectoryReaderTest, TestJsonRequest, ClassificationUpdateProcessorFactoryTest, SolrIndexConfigTest, TestScoreJoinQPNoScore, TestExtendedDismaxParser, DistributedExpandComponentTest, TestSearcherReuse, TestSolrQueryParserResource, TestNoOpRegenerator, TestLRUCache, TestSubQueryTransformerDistrib, QueryElevationComponentTest, TestSortingResponseWriter, TestDistributedSearch, TestSolrCLIRunExample, HdfsUnloadDistributedZkTest, TestAddFieldRealTimeGet, TestSubQueryTransformer, CollectionStateFormat2Test, TestWordDelimiterFilterFactory, TestObjectReleaseTracker, TestLegacyFieldCache, TestHighFrequencyDictionaryFactory, TestSolrFieldCacheMBean, JSONWriterTest, ZkStateReaderTest]
[junit4] Completed [186/613 (1!)] on J0 in 31.97s, 6 tests, 1 error <<< FAILURES!
[...truncated 1380 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:740: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:684: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\common-build.xml:531: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1427: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:984: There were test failures: 613 suites (10 ignored), 2567 tests, 1 error, 124 ignored (80 assumptions) [seed: 76EA0A5A9CBB0D95]
Total time: 68 minutes 4 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