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/04/02 19:57:54 UTC

[JENKINS] Lucene-Solr-Tests-master - Build # 1053 - Still Failing

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1053/

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([B08E5AB945512750:DBC1FAC43C5EFA6A]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:134)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:45)
	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 12142 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_B08E5AB945512750-001/init-core-data-001
   [junit4]   2> 2405163 INFO  (SUITE-ZkStateReaderTest-seed#[B08E5AB945512750]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 2405175 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 2405176 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2405176 INFO  (Thread-3888) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2405176 INFO  (Thread-3888) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2405277 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer start zk server on port:44527
   [junit4]   2> 2405277 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405285 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405305 INFO  (zkCallback-1723-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b03220a name:ZooKeeperConnection Watcher:127.0.0.1:44527 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405305 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405305 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405319 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405329 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405345 INFO  (zkCallback-1724-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2330d30e name:ZooKeeperConnection Watcher:127.0.0.1:44527 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405349 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2405366 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405366 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405367 INFO  (zkCallback-1725-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4497615e name:ZooKeeperConnection Watcher:127.0.0.1:44527/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405367 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405367 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405368 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2405369 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 2405370 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2405371 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2405377 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2405385 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2405393 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2405393 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 2405394 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 2405394 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 2405394 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 2405395 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 2405396 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 2405396 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$5/439641745@4e4ee7fe rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4a716aef[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 2405397 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$5/439641745@77e904d rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4a716aef[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 2405404 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44527 44527
   [junit4]   2> 2405629 INFO  (Thread-3888) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44527 44527
   [junit4]   2> 2405637 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 2405657 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 2405657 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2405658 INFO  (Thread-3889) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2405658 INFO  (Thread-3889) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2405758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer start zk server on port:56511
   [junit4]   2> 2405758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405759 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405761 INFO  (zkCallback-1726-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4edd2677 name:ZooKeeperConnection Watcher:127.0.0.1:56511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405761 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405761 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405762 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405763 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405765 INFO  (zkCallback-1727-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@51412367 name:ZooKeeperConnection Watcher:127.0.0.1:56511 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405765 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405765 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405766 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2405768 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2405768 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2405769 INFO  (zkCallback-1728-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@563e20af name:ZooKeeperConnection Watcher:127.0.0.1:56511/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2405769 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2405770 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2405770 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2405771 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 2405772 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2405773 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2405774 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2405775 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2405776 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2405776 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 2405776 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 2405777 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 2405777 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 2405777 INFO  (zkCallback-1728-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 2405778 INFO  (zkCallback-1728-thread-1) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 2405778 INFO  (zkCallback-1728-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> 2405845 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 2405865 INFO  (zkCallback-1728-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> 2406019 INFO  (zkCallback-1728-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 2406071 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56511 56511
   [junit4]   2> 2406337 INFO  (Thread-3889) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56511 56511
   [junit4]   2> 2406338 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 2406340 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 2406341 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2406353 INFO  (Thread-3890) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2406353 INFO  (Thread-3890) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2406474 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer start zk server on port:47790
   [junit4]   2> 2406474 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2406520 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2406533 INFO  (zkCallback-1729-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a11ec9c name:ZooKeeperConnection Watcher:127.0.0.1:47790 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2406537 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2406537 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2406538 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2406539 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2406552 INFO  (zkCallback-1730-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47ce7f1 name:ZooKeeperConnection Watcher:127.0.0.1:47790 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2406552 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2406552 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2406552 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2406566 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2406567 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2406569 INFO  (zkCallback-1731-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c5d6e86 name:ZooKeeperConnection Watcher:127.0.0.1:47790/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2406569 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2406586 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2406586 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2406587 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 2406588 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2406589 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2406589 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2406589 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2406590 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2406590 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 2406591 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 2406592 INFO  (zkCallback-1731-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 2406593 WARN  (zkCallback-1731-thread-1) [    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections]
   [junit4]   2> 2406600 INFO  (zkCallback-1731-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> 2406600 WARN  (zkCallback-1731-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> 2406601 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47790 47790
   [junit4]   2> 2406756 INFO  (Thread-3890) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47790 47790
   [junit4]   2> 2406758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefreshLazy -Dtests.seed=B08E5AB945512750 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=bg -Dtests.timezone=Egypt -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.42s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([B08E5AB945512750:DBC1FAC43C5EFA6A]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:134)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:45)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2406767 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 2406767 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2406769 INFO  (Thread-3891) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2406769 INFO  (Thread-3891) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2406869 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer start zk server on port:56614
   [junit4]   2> 2406869 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2406870 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2406901 INFO  (zkCallback-1732-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a3195e7 name:ZooKeeperConnection Watcher:127.0.0.1:56614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2406901 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2406901 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2406921 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2406929 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2406941 INFO  (zkCallback-1733-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d07d0b8 name:ZooKeeperConnection Watcher:127.0.0.1:56614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2406941 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2406942 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2406942 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2407029 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2407037 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2407061 INFO  (zkCallback-1734-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@38de6b2b name:ZooKeeperConnection Watcher:127.0.0.1:56614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2407061 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2407061 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2407069 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2407087 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 2407088 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2407090 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2407091 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2407091 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2407092 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2407092 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 2407093 INFO  (zkCallback-1734-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 2407093 INFO  (zkCallback-1734-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> 2407094 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 2407094 INFO  (zkCallback-1734-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> 2407097 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56614 56614
   [junit4]   2> 2407572 INFO  (Thread-3891) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56614 56614
   [junit4]   2> 2407578 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 2407583 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 2407589 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2407601 INFO  (Thread-3892) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2407601 INFO  (Thread-3892) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2407701 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer start zk server on port:54654
   [junit4]   2> 2407701 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2407713 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2407725 INFO  (zkCallback-1735-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@508c0750 name:ZooKeeperConnection Watcher:127.0.0.1:54654 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2407726 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2407726 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2407729 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2407730 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2407730 INFO  (zkCallback-1736-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d806832 name:ZooKeeperConnection Watcher:127.0.0.1:54654 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2407730 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2407731 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2407731 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2407732 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2407732 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2407733 INFO  (zkCallback-1737-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62a7f901 name:ZooKeeperConnection Watcher:127.0.0.1:54654/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2407733 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2407733 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2407733 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2407734 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 2407735 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2407735 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2407736 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 2407736 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 2407737 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 2407737 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 2407737 INFO  (zkCallback-1737-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 2407738 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 2407738 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 2407738 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 2407738 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 2407739 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54654 54654
   [junit4]   2> 2407930 INFO  (Thread-3892) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54654 54654
   [junit4]   2> 2407932 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B08E5AB945512750]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 2407932 INFO  (SUITE-ZkStateReaderTest-seed#[B08E5AB945512750]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_B08E5AB945512750-001
   [junit4]   2> Apr 02, 2016 5:49:44 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=1277, maxMBSortInHeap=4.875463942588656, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=bg, timezone=Egypt
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=232235528,total=524288000
   [junit4]   2> NOTE: All tests run in this JVM: [TestDistributedStatsComponentCardinality, FieldMutatingUpdateProcessorTest, TestXIncludeConfig, TestXmlQParser, OverseerTest, SolrCoreCheckLockOnStartupTest, TestAddFieldRealTimeGet, TestIndexSearcher, TestSolrDeletionPolicy1, WrapperMergePolicyFactoryTest, TestJettySolrRunner, TestFuzzyAnalyzedSuggestions, NoCacheHeaderTest, TestSolrIndexConfig, SSLMigrationTest, BadCopyFieldTest, TestCopyFieldCollectionResource, TestObjectReleaseTracker, TestMaxScoreQueryParser, TestCollationField, TestExceedMaxTermLength, DirectUpdateHandlerOptimizeTest, DistributedQueryComponentOptimizationTest, LukeRequestHandlerTest, CdcrUpdateLogTest, TestShardHandlerFactory, TestPHPSerializedResponseWriter, TestRestManager, TestClassicSimilarityFactory, SearchHandlerTest, SimpleFacetsTest, HdfsLockFactoryTest, TestJmxIntegration, TestCharFilters, TestIndexingPerformance, HdfsBasicDistributedZk2Test, CleanupOldIndexTest, BinaryUpdateRequestHandlerTest, TestDistributedMissingSort, XmlUpdateRequestHandlerTest, StatsComponentTest, UnloadDistributedZkTest, TlogReplayBufferedWhileIndexingTest, TestPerFieldSimilarityWithDefaultOverride, TestAuthorizationFramework, TestStandardQParsers, HdfsSyncSliceTest, LeaderInitiatedRecoveryOnCommitTest, TestDistribIDF, HdfsUnloadDistributedZkTest, SliceStateTest, RequestHandlersTest, TestPostingsSolrHighlighter, PrimitiveFieldTypeTest, SuggesterTSTTest, TestPerFieldSimilarity, TestFilteredDocIdSet, DateMathParserTest, CoreAdminHandlerTest, TestStressReorder, TestCloudSchemaless, HighlighterConfigTest, TestElisionMultitermQuery, SmileWriterTest, MultiThreadedOCPTest, TestOverriddenPrefixQueryForCustomFieldType, TolerantUpdateProcessorTest, CoreMergeIndexesAdminHandlerTest, TestRestoreCore, TestCSVResponseWriter, DocValuesMultiTest, BadIndexSchemaTest, DirectSolrConnectionTest, TestConfigSetsAPIZkFailure, RecoveryZkTest, RegexBoostProcessorTest, TestComplexPhraseQParserPlugin, DistribJoinFromCollectionTest, LeaderElectionTest, DebugComponentTest, ConnectionManagerTest, SolrCloudExampleTest, TestStressRecovery, TestDynamicFieldResource, TestSerializedLuceneMatchVersion, TestSuggestSpellingConverter, HardAutoCommitTest, TestFastWriter, DirectUpdateHandlerTest, TestSolrCloudWithKerberosAlt, ForceLeaderTest, TestUniqueKeyFieldResource, AutoCommitTest, TestDistributedGrouping, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestJoin, TestStressVersions, TestSolr4Spatial, PeerSyncTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SuggesterFSTTest, SuggesterTest, SpatialFilterTest, TestPseudoReturnFields, StatelessScriptUpdateProcessorFactoryTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, QueryParsingTest, TestBinaryResponseWriter, UpdateParamsTest, BadComponentTest, MultiTermTest, TestSearchPerf, TestLuceneMatchVersion, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestJmxMonitoredMap, PluginInfoTest, PreAnalyzedFieldTest, ClusterStateTest, TestRTGBase, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestSimpleTrackingShardHandler, TestTolerantSearch, TestEmbeddedSolrServerConstructors, ActionThrottleTest, AliasIntegrationTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteStatusTest, DistributedQueueTest, DistributedVersionInfoTest, OverseerCollectionConfigSetProcessorTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestCloudDeleteByQuery, TestCollectionAPI, TestDistribDocBasedVersion, TestLeaderInitiatedRecoveryThread, TestRandomRequestDistribution, TestReplicaProperties, TestSizeLimitedDistributedMap, HdfsChaosMonkeySafeLeaderTest, HdfsNNFailoverTest, TestClusterStateMutator, ZkStateReaderTest]
   [junit4] Completed [469/584 (1!)] on J2 in 3.07s, 5 tests, 1 error <<< FAILURES!

[...truncated 378 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1014: There were test failures: 584 suites (10 ignored), 2353 tests, 1 error, 85 ignored (70 assumptions) [seed: B08E5AB945512750]

Total time: 93 minutes 10 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




[JENKINS] Lucene-Solr-Tests-master - Build # 1054 - Still Failing

Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1054/

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([AB664A3E51000B78:C029EA43280FD642]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:134)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:45)
	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 10846 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_AB664A3E51000B78-001/init-core-data-001
   [junit4]   2> 400258 INFO  (SUITE-ZkStateReaderTest-seed#[AB664A3E51000B78]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 400262 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 400263 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 400271 INFO  (Thread-803) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 400271 INFO  (Thread-803) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 400367 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer start zk server on port:42811
   [junit4]   2> 400368 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 400387 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 400514 INFO  (zkCallback-168-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79acf2d3 name:ZooKeeperConnection Watcher:127.0.0.1:42811 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 400514 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 400514 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 400528 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 400531 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 400567 INFO  (zkCallback-169-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@574eab36 name:ZooKeeperConnection Watcher:127.0.0.1:42811 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 400567 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 400567 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 400567 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 400574 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 400574 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 400575 INFO  (zkCallback-170-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@772851d8 name:ZooKeeperConnection Watcher:127.0.0.1:42811/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 400575 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 400576 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 400576 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 400577 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 400578 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 400581 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 400581 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 400582 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 400583 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 400584 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 400584 INFO  (zkCallback-170-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 400585 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 400585 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 400586 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 400586 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 400589 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42811 42811
   [junit4]   2> 400943 INFO  (Thread-803) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42811 42811
   [junit4]   2> 400955 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 400959 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 400960 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 400971 INFO  (Thread-804) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 400971 INFO  (Thread-804) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 401209 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer start zk server on port:33868
   [junit4]   2> 401209 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401212 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401235 INFO  (zkCallback-171-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4af9cbc2 name:ZooKeeperConnection Watcher:127.0.0.1:33868 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401239 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401239 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401271 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401272 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401278 INFO  (zkCallback-172-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2da275a8 name:ZooKeeperConnection Watcher:127.0.0.1:33868 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401278 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401278 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401278 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 401281 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401281 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401282 INFO  (zkCallback-173-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f306215 name:ZooKeeperConnection Watcher:127.0.0.1:33868/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401282 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401283 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401283 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 401292 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 401292 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 401293 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 401294 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 401296 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 401297 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 401298 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 401298 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 401298 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 401298 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 401300 INFO  (zkCallback-173-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 401301 INFO  (zkCallback-173-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> 401301 INFO  (zkCallback-173-thread-1) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 401306 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 401307 INFO  (zkCallback-173-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> 401309 INFO  (zkCallback-173-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 401371 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33868 33868
   [junit4]   2> 401595 INFO  (Thread-804) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33868 33868
   [junit4]   2> 401607 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 401631 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 401631 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 401639 INFO  (Thread-805) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 401639 INFO  (Thread-805) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 401735 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer start zk server on port:59107
   [junit4]   2> 401735 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401751 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401786 INFO  (zkCallback-174-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@38d03404 name:ZooKeeperConnection Watcher:127.0.0.1:59107 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401786 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401787 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401795 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401800 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401809 INFO  (zkCallback-175-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8794fc7 name:ZooKeeperConnection Watcher:127.0.0.1:59107 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401809 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401809 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401809 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 401811 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 401812 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 401813 INFO  (zkCallback-176-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14e2b44e name:ZooKeeperConnection Watcher:127.0.0.1:59107/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 401813 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 401813 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 401814 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 401815 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 401815 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 401816 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 401821 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 401821 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 401822 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 401822 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 401823 INFO  (zkCallback-176-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 401824 INFO  (zkCallback-176-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> 401825 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 401826 INFO  (zkCallback-176-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> 401834 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59107 59107
   [junit4]   2> 402110 INFO  (Thread-805) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59107 59107
   [junit4]   2> 402112 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 402121 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 402122 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 402122 INFO  (Thread-806) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 402122 INFO  (Thread-806) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 402223 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer start zk server on port:46570
   [junit4]   2> 402223 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402227 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402263 INFO  (zkCallback-177-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@fb694a1 name:ZooKeeperConnection Watcher:127.0.0.1:46570 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402263 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402263 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402268 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402287 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402319 INFO  (zkCallback-178-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@507f0aea name:ZooKeeperConnection Watcher:127.0.0.1:46570 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402323 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402323 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402323 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 402372 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402383 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402403 INFO  (zkCallback-179-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6e7cde99 name:ZooKeeperConnection Watcher:127.0.0.1:46570/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402407 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402407 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402415 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 402416 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 402416 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 402417 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 402417 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 402418 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 402418 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 402419 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 402419 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 402419 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 402419 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 402421 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 402421 INFO  (zkCallback-179-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> 402422 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 402434 INFO  (zkCallback-179-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 402434 WARN  (zkCallback-179-thread-1) [    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections]
   [junit4]   2> 402435 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46570 46570
   [junit4]   2> 402667 INFO  (Thread-806) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46570 46570
   [junit4]   2> 402668 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 402670 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 402670 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 402671 INFO  (Thread-807) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 402671 INFO  (Thread-807) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 402775 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer start zk server on port:55569
   [junit4]   2> 402775 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402783 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402795 INFO  (zkCallback-180-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f072298 name:ZooKeeperConnection Watcher:127.0.0.1:55569 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402795 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402795 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402797 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 0x153dbb948fd0000, 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> 402798 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402799 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402802 INFO  (zkCallback-181-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e38d5b3 name:ZooKeeperConnection Watcher:127.0.0.1:55569 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402802 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402802 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402802 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 402804 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 402809 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 402875 INFO  (zkCallback-182-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1709caf6 name:ZooKeeperConnection Watcher:127.0.0.1:55569/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 402875 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 402875 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 402883 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 402909 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 402909 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 402910 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 402911 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 402915 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 402915 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 402916 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 402917 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 402931 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$5/313611347@43833516 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@47bca9be[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 402931 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55569 55569
   [junit4]   2> 402935 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$5/313611347@6987cf06 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@47bca9be[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 403184 INFO  (Thread-807) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55569 55569
   [junit4]   2> 403185 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[AB664A3E51000B78]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefreshLazy -Dtests.seed=AB664A3E51000B78 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-GT -Dtests.timezone=Europe/Vaduz -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.52s J0 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([AB664A3E51000B78:C029EA43280FD642]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:134)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy(ZkStateReaderTest.java:45)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 403191 INFO  (SUITE-ZkStateReaderTest-seed#[AB664A3E51000B78]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_AB664A3E51000B78-001
   [junit4]   2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=5, maxDocsPerChunk=820, blockSize=531), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=5, blockSize=531)), sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=es-GT, timezone=Europe/Vaduz
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=144655832,total=402128896
   [junit4]   2> NOTE: All tests run in this JVM: [SOLR749Test, TestRTGBase, TestReplicationHandler, TestMissingGroups, HdfsBasicDistributedZkTest, TestCrossCoreJoin, DistributedDebugComponentTest, PeerSyncTest, TestRandomDVFaceting, URLClassifyProcessorTest, TestCollationFieldDocValues, HttpPartitionTest, TestJsonFacets, TestArbitraryIndexDir, SaslZkACLProviderTest, ZkStateReaderTest]
   [junit4] Completed [71/584 (1!)] on J0 in 2.99s, 5 tests, 1 error <<< FAILURES!

[...truncated 1669 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1014: There were test failures: 584 suites (10 ignored), 2353 tests, 1 error, 85 ignored (70 assumptions) [seed: AB664A3E51000B78]

Total time: 96 minutes 56 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