You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/06/26 05:48:50 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 287 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/287/
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Could not find collection : c1
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
at __randomizedtesting.SeedInfo.seed([C353DB3A972E4352:BCCD6CBFFE4C6ED8]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:51)
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 12203 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_C353DB3A972E4352-001/init-core-data-001
[junit4] 2> 2001956 INFO (SUITE-ZkStateReaderTest-seed#[C353DB3A972E4352]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2001958 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2001958 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2001958 INFO (Thread-4689) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2001959 INFO (Thread-4689) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2002059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:59662
[junit4] 2> 2002059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002061 INFO (zkCallback-2413-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ea738ba name:ZooKeeperConnection Watcher:127.0.0.1:59662 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002061 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002061 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002062 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002062 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002062 INFO (zkCallback-2414-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@391b0844 name:ZooKeeperConnection Watcher:127.0.0.1:59662 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2002066 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002066 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002067 INFO (zkCallback-2415-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7bae2a2c name:ZooKeeperConnection Watcher:127.0.0.1:59662/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002067 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002067 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002067 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2002068 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2002069 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2002069 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2002070 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2002070 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2002071 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2002071 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2002072 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2002072 INFO (zkCallback-2415-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2002073 INFO (zkCallback-2415-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> 2002074 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2002075 INFO (zkCallback-2415-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> 2002076 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59662 59662
[junit4] 2> 2002255 INFO (Thread-4689) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59662 59662
[junit4] 2> 2002256 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2002259 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 2002259 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2002259 INFO (Thread-4690) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2002259 INFO (Thread-4690) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2002361 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:32914
[junit4] 2> 2002361 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002369 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002401 INFO (zkCallback-2417-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15593655 name:ZooKeeperConnection Watcher:127.0.0.1:32914 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002405 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002405 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002437 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002445 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002454 INFO (zkCallback-2418-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56493ba0 name:ZooKeeperConnection Watcher:127.0.0.1:32914 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002454 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002454 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002454 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2002459 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002460 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002460 INFO (zkCallback-2419-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@793ca411 name:ZooKeeperConnection Watcher:127.0.0.1:32914/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002460 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002460 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002461 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2002461 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2002462 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2002463 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2002463 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2002464 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2002465 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2002465 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2002466 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2002467 INFO (zkCallback-2419-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2002467 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2002468 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2002469 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 0x1558b3a41f10002, 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> 2002470 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32914 32914
[junit4] 2> 2002589 INFO (Thread-4690) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32914 32914
[junit4] 2> 2002590 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 2002592 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 2002592 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2002593 INFO (Thread-4691) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2002593 INFO (Thread-4691) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2002693 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:56107
[junit4] 2> 2002693 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002694 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002695 INFO (zkCallback-2421-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@538df451 name:ZooKeeperConnection Watcher:127.0.0.1:56107 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002695 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002695 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002696 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002697 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002697 INFO (zkCallback-2422-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@732576d3 name:ZooKeeperConnection Watcher:127.0.0.1:56107 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002697 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002697 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002697 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2002701 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2002702 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2002702 INFO (zkCallback-2423-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@207d1482 name:ZooKeeperConnection Watcher:127.0.0.1:56107/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2002702 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2002702 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2002703 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2002703 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2002704 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2002704 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2002705 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2002706 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2002706 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2002706 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2002707 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2002709 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2002709 INFO (zkCallback-2423-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2002710 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2002711 INFO (zkCallback-2423-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> 2002711 INFO (zkCallback-2423-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2002762 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56107 56107
[junit4] 2> 2002938 INFO (Thread-4691) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56107 56107
[junit4] 2> 2002939 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 2002942 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2002942 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2002943 INFO (Thread-4692) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2002943 INFO (Thread-4692) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2003043 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:51509
[junit4] 2> 2003043 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003043 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003046 INFO (zkCallback-2425-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30e037e2 name:ZooKeeperConnection Watcher:127.0.0.1:51509 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003048 INFO (zkCallback-2426-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11ba49c1 name:ZooKeeperConnection Watcher:127.0.0.1:51509 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003048 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003048 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003048 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2003050 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003050 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003050 INFO (zkCallback-2427-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b1eaa3b name:ZooKeeperConnection Watcher:127.0.0.1:51509/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003050 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003051 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003051 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2003052 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2003052 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2003053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2003053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2003054 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2003054 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2003055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2003055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2003056 INFO (zkCallback-2427-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2003056 INFO (zkCallback-2427-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> 2003108 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2003108 INFO (zkCallback-2427-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> 2003110 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51509 51509
[junit4] 2> 2003214 INFO (Thread-4692) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51509 51509
[junit4] 2> 2003217 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2003220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2003220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2003221 INFO (Thread-4693) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2003221 INFO (Thread-4693) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2003321 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:48957
[junit4] 2> 2003321 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003321 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003323 INFO (zkCallback-2429-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d5bdcbb name:ZooKeeperConnection Watcher:127.0.0.1:48957 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003323 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003323 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003324 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003324 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003325 INFO (zkCallback-2430-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@26e8ea97 name:ZooKeeperConnection Watcher:127.0.0.1:48957 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2003327 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003327 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003327 INFO (zkCallback-2431-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b8bcc96 name:ZooKeeperConnection Watcher:127.0.0.1:48957/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003328 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003328 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003328 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2003329 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2003329 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2003330 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2003331 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2003331 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2003332 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2003332 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2003333 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2003333 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2003334 INFO (zkCallback-2431-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2003335 INFO (zkCallback-2431-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> 2003335 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2003336 INFO (zkCallback-2431-thread-2) [ ] 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> 2003336 INFO (zkCallback-2431-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> 2003336 INFO (zkCallback-2431-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2003338 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48957 48957
[junit4] 2> 2003461 INFO (Thread-4693) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48957 48957
[junit4] 2> 2003462 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2003464 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 2003465 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2003465 INFO (Thread-4694) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2003465 INFO (Thread-4694) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2003565 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer start zk server on port:40467
[junit4] 2> 2003565 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003566 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003567 INFO (zkCallback-2433-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9f9312 name:ZooKeeperConnection Watcher:127.0.0.1:40467 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003568 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003568 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003568 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003573 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003573 INFO (zkCallback-2434-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ca44948 name:ZooKeeperConnection Watcher:127.0.0.1:40467 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003573 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003573 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003573 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2003575 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2003577 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2003580 INFO (zkCallback-2435-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12884922 name:ZooKeeperConnection Watcher:127.0.0.1:40467/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2003580 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2003581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2003581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2003582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2003582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2003583 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2003583 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2003584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2003584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2003585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2003585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2003586 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2003586 INFO (zkCallback-2435-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2003589 INFO (zkCallback-2435-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> 2003637 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2003638 INFO (zkCallback-2435-thread-2) [ ] 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> 2003638 INFO (zkCallback-2435-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> 2003638 INFO (zkCallback-2435-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2003640 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40467 40467
[junit4] 2> 2003811 INFO (Thread-4694) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40467 40467
[junit4] 2> 2003812 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C353DB3A972E4352]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=C353DB3A972E4352 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ro-RO -Dtests.timezone=America/Aruba -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.35s J1 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([C353DB3A972E4352:BCCD6CBFFE4C6ED8]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:51)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2003816 INFO (SUITE-ZkStateReaderTest-seed#[C353DB3A972E4352]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_C353DB3A972E4352-001
[junit4] 2> Jun 26, 2016 5:41:40 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=1112, maxMBSortInHeap=5.168745285324478, sim=ClassicSimilarity, locale=ro-RO, timezone=America/Aruba
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=216162984,total=529006592
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrCLIRunExample, RuleEngineTest, TestPerFieldSimilarityClassic, SpellCheckComponentTest, ZkCLITest, DateRangeFieldTest, CdcrReplicationHandlerTest, DistanceFunctionTest, DistributedFacetPivotSmallTest, CoreMergeIndexesAdminHandlerTest, TestDocumentBuilder, TestDFRSimilarityFactory, TestDefaultStatsCache, RequestLoggingTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, UniqFieldsUpdateProcessorFactoryTest, TestSSLRandomization, TestJsonRequest, BlockCacheTest, TestDynamicFieldResource, MoreLikeThisHandlerTest, SolrIndexConfigTest, TestHashPartitioner, TestSolrDeletionPolicy2, TestTolerantUpdateProcessorCloud, SpellCheckCollatorTest, TestCollectionAPI, TestCursorMarkWithoutUniqueKey, SaslZkACLProviderTest, TestNumericTerms64, AnalyticsQueryTest, CollectionsAPIAsyncDistributedZkTest, TestMiniSolrCloudClusterBase, SolrPluginUtilsTest, TestSimpleTrackingShardHandler, DeleteReplicaTest, GraphQueryTest, ZkControllerTest, AsyncMigrateRouteKeyTest, ConfigSetsAPITest, DocValuesTest, RestartWhileUpdatingTest, TestMaxScoreQueryParser, TestTrackingShardHandlerFactory, DOMUtilTest, ZkSolrClientTest, PKIAuthenticationIntegrationTest, TestCopyFieldCollectionResource, TestQueryTypes, SolrIndexSplitterTest, DistribCursorPagingTest, SpellPossibilityIteratorTest, SuggesterFSTTest, CSVRequestHandlerTest, TriLevelCompositeIdRoutingTest, TestFastLRUCache, TestCoreContainer, TestDistributedStatsComponentCardinality, TermsComponentTest, TestCloudDeleteByQuery, SoftAutoCommitTest, DocumentBuilderTest, RankQueryTest, TestFiltering, CollectionReloadTest, NotRequiredUniqueKeyTest, SharedFSAutoReplicaFailoverUtilsTest, TestIntervalFaceting, AliasIntegrationTest, RecoveryAfterSoftCommitTest, OpenCloseCoreStressTest, DistanceUnitsTest, TestGraphTermsQParserPlugin, SpatialRPTFieldTypeTest, TestChildDocTransformer, HdfsDirectoryTest, DistributedMLTComponentTest, IndexBasedSpellCheckerTest, HdfsThreadLeakTest, TestUseDocValuesAsStored, ClassificationUpdateProcessorFactoryTest, TestUniqueKeyFieldResource, TestReloadDeadlock, DirectSolrSpellCheckerTest, TestPartialUpdateDeduplication, TestSolrConfigHandlerConcurrent, UpdateRequestProcessorFactoryTest, TimeZoneUtilsTest, HttpPartitionTest, TestOrdValues, SimplePostToolTest, TestXmlQParser, TestFileDictionaryLookup, TermVectorComponentTest, TestFieldTypeCollectionResource, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, LeaderElectionTest, ShardRoutingCustomTest, TestDistributedSearch, CurrencyFieldOpenExchangeTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestFoldingMultitermQuery, TestStressLucene, TestTrie, SuggesterWFSTTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, TestUpdate, TestWordDelimiterFilterFactory, SortByFunctionTest, SolrInfoMBeanTest, TestSurroundQueryParser, TestQueryUtils, TestWriterPerf, FileBasedSpellCheckerTest, TestSolrQueryParser, FastVectorHighlighterTest, LoggingHandlerTest, TestCollationField, ReturnFieldsTest, MBeansHandlerTest, TestBinaryResponseWriter, TestLFUCache, PreAnalyzedFieldTest, TestSystemIdResolver, SpellingQueryConverterTest, TestSolrJ, TestLRUCache, SystemInfoHandlerTest, FileUtilsTest, CircularListTest, CursorPagingTest, DistributedIntervalFacetingTest, TestTolerantSearch, TestEmbeddedSolrServerConstructors, TestJettySolrRunner, CdcrVersionReplicationTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, ForceLeaderTest, SharedFSAutoReplicaFailoverTest, SolrCloudExampleTest, TestCloudBackupRestore, TestCloudInspectUtil, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestLeaderInitiatedRecoveryThread, TestRandomRequestDistribution, TestSizeLimitedDistributedMap, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, TestClusterStateMutator, ZkStateReaderTest]
[junit4] Completed [495/617 (1!)] on J1 in 2.63s, 6 tests, 1 error <<< FAILURES!
[...truncated 402 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:530: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:984: There were test failures: 617 suites (10 ignored), 2597 tests, 1 error, 87 ignored (72 assumptions) [seed: C353DB3A972E4352]
Total time: 68 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any