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/03/18 11:19:34 UTC

[JENKINS] Lucene-Solr-Tests-6.x - Build # 73 - Failure

Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/73/

1 tests failed.
FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([C008007DF3246859:AB47A0008A2BB563]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:46)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 12078 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/J2/temp/solr.cloud.overseer.ZkStateReaderTest_C008007DF3246859-001/init-core-data-001
   [junit4]   2> 1822779 INFO  (SUITE-ZkStateReaderTest-seed#[C008007DF3246859]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 1822782 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 1822782 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1822782 INFO  (Thread-5273) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1822782 INFO  (Thread-5273) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1822882 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer start zk server on port:36810
   [junit4]   2> 1822883 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1822884 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1822886 INFO  (zkCallback-2238-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39578098 name:ZooKeeperConnection Watcher:127.0.0.1:36810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1822886 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1822886 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1822889 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1822899 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1822902 INFO  (zkCallback-2239-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c87ecf6 name:ZooKeeperConnection Watcher:127.0.0.1:36810 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1822902 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1822902 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1822903 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1822907 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1822908 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1822911 INFO  (zkCallback-2240-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f186258 name:ZooKeeperConnection Watcher:127.0.0.1:36810/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1822911 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1822911 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1822912 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1822913 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1822914 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1822915 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1822918 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1822919 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1822920 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1822921 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1822923 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1822925 INFO  (zkCallback-2240-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1822925 WARN  (zkCallback-2240-thread-1) [    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections]
   [junit4]   2> 1822926 INFO  (zkCallback-2240-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> 1822926 WARN  (zkCallback-2240-thread-2) [    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /clusterstate.json]
   [junit4]   2> 1822928 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36810 36810
   [junit4]   2> 1823145 INFO  (Thread-5273) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36810 36810
   [junit4]   2> 1823147 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefreshLazy -Dtests.seed=C008007DF3246859 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-MT -Dtests.timezone=US/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.37s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([C008007DF3246859:AB47A0008A2BB563]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:46)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1823152 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 1823152 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1823152 INFO  (Thread-5274) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1823152 INFO  (Thread-5274) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1823252 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer start zk server on port:39068
   [junit4]   2> 1823253 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823253 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823255 INFO  (zkCallback-2241-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10486e25 name:ZooKeeperConnection Watcher:127.0.0.1:39068 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823255 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823256 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823259 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823259 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 0x1538935af2e0000, 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> 1823260 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823261 INFO  (zkCallback-2242-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f48aef4 name:ZooKeeperConnection Watcher:127.0.0.1:39068 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823261 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823262 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823262 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1823265 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823265 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 0x1538935af2e0001, 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> 1823265 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823266 INFO  (zkCallback-2243-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@38b18579 name:ZooKeeperConnection Watcher:127.0.0.1:39068/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823267 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823267 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823269 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1823271 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1823272 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1823273 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1823274 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1823275 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1823276 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1823277 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 1823277 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1823278 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1823278 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1823279 INFO  (zkCallback-2243-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1823280 INFO  (zkCallback-2243-thread-1) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1823280 INFO  (zkCallback-2243-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> 1823281 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1823282 INFO  (zkCallback-2243-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> 1823283 INFO  (zkCallback-2243-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1823286 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39068 39068
   [junit4]   2> 1823496 INFO  (Thread-5274) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39068 39068
   [junit4]   2> 1823499 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 1823504 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 1823504 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1823504 INFO  (Thread-5275) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1823504 INFO  (Thread-5275) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1823604 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer start zk server on port:47857
   [junit4]   2> 1823605 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823605 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823608 INFO  (zkCallback-2244-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e7ed9b8 name:ZooKeeperConnection Watcher:127.0.0.1:47857 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823608 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823608 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823615 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823616 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823617 INFO  (zkCallback-2245-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24578401 name:ZooKeeperConnection Watcher:127.0.0.1:47857 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823617 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823617 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823617 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1823619 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823622 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823623 INFO  (zkCallback-2246-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@206ac97e name:ZooKeeperConnection Watcher:127.0.0.1:47857/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823623 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823623 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823624 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1823625 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1823626 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1823627 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1823628 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1823629 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1823630 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1823630 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1823631 INFO  (zkCallback-2246-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1823631 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1823632 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 1823633 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1823633 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 1823634 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47857 47857
   [junit4]   2> 1823773 INFO  (Thread-5275) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47857 47857
   [junit4]   2> 1823775 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 1823780 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 1823781 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1823781 INFO  (Thread-5276) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1823781 INFO  (Thread-5276) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1823881 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer start zk server on port:48670
   [junit4]   2> 1823881 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823882 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823885 INFO  (zkCallback-2247-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ab3190c name:ZooKeeperConnection Watcher:127.0.0.1:48670 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823885 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823886 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823887 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823887 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823888 INFO  (zkCallback-2248-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4338e7fe name:ZooKeeperConnection Watcher:127.0.0.1:48670 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823888 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823889 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823889 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1823892 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1823893 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1823896 INFO  (zkCallback-2249-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ea808cf name:ZooKeeperConnection Watcher:127.0.0.1:48670/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1823896 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1823896 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1823897 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1823899 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1823900 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1823901 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1823902 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1823904 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1823905 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1823905 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1823907 INFO  (zkCallback-2249-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1823908 INFO  (zkCallback-2249-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> 1823910 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1823911 INFO  (zkCallback-2249-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> 1823964 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48670 48670
   [junit4]   2> 1824169 INFO  (Thread-5276) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48670 48670
   [junit4]   2> 1824171 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 1824174 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 1824175 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1824175 INFO  (Thread-5277) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1824175 INFO  (Thread-5277) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1824275 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer start zk server on port:37528
   [junit4]   2> 1824275 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1824276 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1824278 INFO  (zkCallback-2250-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30751f40 name:ZooKeeperConnection Watcher:127.0.0.1:37528 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1824279 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1824279 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1824280 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1824281 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1824281 INFO  (zkCallback-2251-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f35a020 name:ZooKeeperConnection Watcher:127.0.0.1:37528 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1824281 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1824282 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1824282 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1824285 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1824286 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1824286 INFO  (zkCallback-2252-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2313b58b name:ZooKeeperConnection Watcher:127.0.0.1:37528/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1824286 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1824287 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1824287 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1824289 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 1824290 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1824291 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1824292 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1824293 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1824295 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1824295 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 1824296 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1824296 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1824296 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 1824298 INFO  (zkCallback-2252-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 1824299 INFO  (zkCallback-2252-thread-1) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 1824299 INFO  (zkCallback-2252-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> 1824350 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 1824351 INFO  (zkCallback-2252-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> 1824352 INFO  (zkCallback-2252-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 1824404 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37528 37528
   [junit4]   2> 1824827 INFO  (Thread-5277) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37528 37528
   [junit4]   2> 1824828 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C008007DF3246859]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 1824829 INFO  (SUITE-ZkStateReaderTest-seed#[C008007DF3246859]-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/J2/temp/solr.cloud.overseer.ZkStateReaderTest_C008007DF3246859-001
   [junit4]   2> Mar 18, 2016 10:11:51 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(Lucene60): {}, docValues:{}, maxPointsInLeafNode=172, maxMBSortInHeap=4.413606692419549, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=en-MT, timezone=US/Central
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=206204872,total=523763712
   [junit4]   2> NOTE: All tests run in this JVM: [TestZkChroot, TestJettySolrRunner, SuggesterTest, TestSolrDeletionPolicy1, OverseerTaskQueueTest, TestPHPSerializedResponseWriter, TestFieldResource, TestDistributedMissingSort, TestUtils, HdfsDirectoryTest, TestReqParamsAPI, TestPerFieldSimilarity, PluginInfoTest, ReplicationFactorTest, BaseCdcrDistributedZkTest, StressHdfsTest, TestSolrQueryParser, PrimitiveFieldTypeTest, RulesTest, HdfsNNFailoverTest, VersionInfoTest, MultiThreadedOCPTest, TestIndexSearcher, LeaderElectionTest, TestElisionMultitermQuery, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestReversedWildcardFilterFactory, TestCollationField, TestDocBasedVersionConstraints, CircularListTest, TestMacros, BadComponentTest, BitVectorTest, XmlUpdateRequestHandlerTest, TestSolrQueryParserResource, TestRebalanceLeaders, FacetPivotSmallTest, TestSolrJ, DirectSolrConnectionTest, HdfsChaosMonkeyNothingIsSafeTest, TestLeaderInitiatedRecoveryThread, TestExtendedDismaxParser, TestJmxMonitoredMap, OverseerCollectionConfigSetProcessorTest, SolrTestCaseJ4Test, TestConfigOverlay, DateMathParserTest, CollectionStateFormat2Test, QueryParsingTest, SharedFSAutoReplicaFailoverTest, HardAutoCommitTest, TestXIncludeConfig, RegexBoostProcessorTest, TestSuggestSpellingConverter, StatelessScriptUpdateProcessorFactoryTest, TestTolerantSearch, VMParamsZkACLAndCredentialsProvidersTest, ConnectionManagerTest, TestSolrCloudWithKerberosAlt, TestWriterPerf, TestSchemaNameResource, TestCoreDiscovery, TestRuleBasedAuthorizationPlugin, AssignTest, TestDownShardTolerantSearch, TestFastWriter, ScriptEngineTest, HighlighterMaxOffsetTest, TestJsonRequest, OverseerTest, TestSolrConfigHandlerCloud, TestManagedResourceStorage, TestGroupingSearch, TestJmxIntegration, TestCharFilters, HdfsTlogReplayBufferedWhileIndexingTest, TestSolrQueryResponse, TestConfigSets, DeleteLastCustomShardedReplicaTest, TestSimpleQParserPlugin, UpdateParamsTest, TestManagedSchema, TestDynamicFieldCollectionResource, NoCacheHeaderTest, BlockCacheTest, CurrencyFieldXmlFileTest, TestManagedSynonymFilterFactory, TestFileDictionaryLookup, TestNamedUpdateProcessors, TestSearchPerf, AnalysisErrorHandlingTest, SyncSliceTest, TestReplicationHandler, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestStressReorder, TestJoin, StatsComponentTest, TestFiltering, AnalysisAfterCoreReloadTest, SolrRequestParserTest, TestTrie, PolyFieldTest, TestAtomicUpdateErrorCases, DocValuesMultiTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, RequestHandlersTest, MoreLikeThisHandlerTest, LoggingHandlerTest, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, BinaryUpdateRequestHandlerTest, TestLFUCache, AsyncCallRequestStatusResponseTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DistribJoinFromCollectionTest, ForceLeaderTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, SSLMigrationTest, SaslZkACLProviderTest, ShardSplitTest, TestMiniSolrCloudClusterSSL, TestRequestStatusCollectionAPI, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, HdfsRestartWhileUpdatingTest, TestClusterStateMutator, ZkStateReaderTest]
   [junit4] Completed [470/581 (1!)] on J2 in 2.32s, 5 tests, 1 error <<< FAILURES!

[...truncated 377 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:526: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1014: There were test failures: 581 suites (10 ignored), 2327 tests, 1 error, 86 ignored (70 assumptions) [seed: C008007DF3246859]

Total time: 61 minutes 51 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