lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.0-Linux (64bit/jdk1.8.0_92) - Build # 182 - Failure!
Date Wed, 25 May 2016 18:57:37 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.0-Linux/182/
Java: 64bit/jdk1.8.0_92 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

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

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([865631A09DC9C22E:99EC40574DA904EB]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:136)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
	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 10554 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_865631A09DC9C22E-001/init-core-data-001
   [junit4]   2> 22930 INFO  (SUITE-ZkStateReaderTest-seed#[865631A09DC9C22E]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 22933 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 22933 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 22933 INFO  (Thread-136) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 22933 INFO  (Thread-136) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 23033 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:42504
   [junit4]   2> 23035 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 23035 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 23038 INFO  (zkCallback-53-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ba5af0d name:ZooKeeperConnection Watcher:127.0.0.1:42504 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 23038 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 23039 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 23046 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 23047 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 23048 INFO  (zkCallback-54-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73182966 name:ZooKeeperConnection Watcher:127.0.0.1:42504 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 23048 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 23049 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 23049 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 23054 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 23054 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 23056 INFO  (zkCallback-55-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@606e897f name:ZooKeeperConnection Watcher:127.0.0.1:42504/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 23056 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 23057 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 23061 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 23062 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 23063 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 23065 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 23066 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 23066 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 23068 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 23068 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 23069 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 23069 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 23070 INFO  (zkCallback-55-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 23071 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 23071 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 23072 INFO  (zkCallback-55-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 23072 INFO  (zkCallback-55-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 23129 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42504 42504
   [junit4]   2> 33149 INFO  (Thread-136) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42504 42504
   [junit4]   2> 33149 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 33151 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 33151 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 33151 INFO  (Thread-137) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 33151 INFO  (Thread-137) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 33251 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:43117
   [junit4]   2> 33251 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33252 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33254 INFO  (zkCallback-56-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33ac98a2 name:ZooKeeperConnection Watcher:127.0.0.1:43117 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33254 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33254 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33255 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33256 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33258 INFO  (zkCallback-57-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63654d05 name:ZooKeeperConnection Watcher:127.0.0.1:43117 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33258 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33258 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33258 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 33259 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 33259 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 33261 INFO  (zkCallback-58-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c1a3256 name:ZooKeeperConnection Watcher:127.0.0.1:43117/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 33261 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 33262 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 33262 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 33262 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 33263 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 33263 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 33264 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 33264 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 33265 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 33265 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 33265 INFO  (zkCallback-58-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 33266 INFO  (zkCallback-58-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> 33266 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 33267 INFO  (zkCallback-58-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> 33321 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43117 43117
   [junit4]   2> 38373 INFO  (Thread-137) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43117 43117
   [junit4]   2> 38374 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 38375 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 38376 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 38376 INFO  (Thread-138) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 38376 INFO  (Thread-138) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 38476 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:41924
   [junit4]   2> 38476 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 38477 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 38479 INFO  (zkCallback-59-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4cbd650f name:ZooKeeperConnection Watcher:127.0.0.1:41924 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 38479 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 38479 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 38484 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 38484 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 38485 INFO  (zkCallback-60-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@563dd9c0 name:ZooKeeperConnection Watcher:127.0.0.1:41924 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 38485 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 38486 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 38486 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 38487 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 38489 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 38491 INFO  (zkCallback-61-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2288926c name:ZooKeeperConnection Watcher:127.0.0.1:41924/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 38491 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 38491 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 38492 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 38493 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 38494 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 38494 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 38495 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 38495 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 38496 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 38496 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 38496 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 38496 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 38497 INFO  (zkCallback-61-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 38497 INFO  (zkCallback-61-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> 38499 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 38499 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 38501 INFO  (zkCallback-61-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 38501 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 38505 INFO  (zkCallback-61-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 38505 INFO  (zkCallback-61-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> 38505 WARN  (zkCallback-61-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> 38507 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41924 41924
   [junit4]   2> 53559 INFO  (Thread-138) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41924 41924
   [junit4]   2> 53560 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=865631A09DC9C22E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=cs -Dtests.timezone=Asia/Tehran -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   15.2s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([865631A09DC9C22E:99EC40574DA904EB]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:136)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 53568 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 53569 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 53569 INFO  (Thread-139) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 53569 INFO  (Thread-139) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 53669 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:44408
   [junit4]   2> 53669 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53670 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53674 INFO  (zkCallback-62-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44194d6 name:ZooKeeperConnection Watcher:127.0.0.1:44408 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53674 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53674 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53678 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53680 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53681 INFO  (zkCallback-63-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a27d796 name:ZooKeeperConnection Watcher:127.0.0.1:44408 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53681 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53682 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53682 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 53684 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53685 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53685 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 0x154e91ceded0001, 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> 53691 INFO  (zkCallback-64-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@31579635 name:ZooKeeperConnection Watcher:127.0.0.1:44408/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53692 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53692 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53692 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 53693 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 53694 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 53695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 53695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 53696 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 53697 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 53698 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 53698 INFO  (zkCallback-64-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 53699 INFO  (zkCallback-64-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> 53700 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 53701 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 53701 INFO  (zkCallback-64-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> 53702 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 53703 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 0x154e91ceded0002, 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> 53704 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44408 44408
   [junit4]   2> 53851 INFO  (Thread-139) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44408 44408
   [junit4]   2> 53851 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 53854 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 53855 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 53855 INFO  (Thread-140) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 53855 INFO  (Thread-140) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 53955 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:33523
   [junit4]   2> 53955 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53956 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53958 INFO  (zkCallback-65-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@35065ff3 name:ZooKeeperConnection Watcher:127.0.0.1:33523 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53958 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53958 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53959 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53959 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53961 INFO  (zkCallback-66-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@217fe7e name:ZooKeeperConnection Watcher:127.0.0.1:33523 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53961 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53962 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53962 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 53963 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 53964 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 53964 INFO  (zkCallback-67-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d6b7e66 name:ZooKeeperConnection Watcher:127.0.0.1:33523/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 53964 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 53965 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 53965 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 53965 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 53966 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 53966 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 53967 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 53967 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 53968 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 53968 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 53968 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 53968 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 53968 INFO  (zkCallback-67-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 53969 INFO  (zkCallback-67-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> 53969 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 53970 INFO  (zkCallback-67-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 53970 INFO  (zkCallback-67-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 53970 INFO  (zkCallback-67-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> 54022 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33523 33523
   [junit4]   2> 69084 INFO  (Thread-140) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33523 33523
   [junit4]   2> 69084 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 69086 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 69087 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 69087 INFO  (Thread-141) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 69087 INFO  (Thread-141) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 69187 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer start zk server on port:33327
   [junit4]   2> 69187 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 69187 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 69190 INFO  (zkCallback-68-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a093292 name:ZooKeeperConnection Watcher:127.0.0.1:33327 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 69190 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 69190 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 69191 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 69191 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 69192 INFO  (zkCallback-69-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48e78e22 name:ZooKeeperConnection Watcher:127.0.0.1:33327 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 69192 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 69192 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 69192 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 69194 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 69194 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 69195 INFO  (zkCallback-70-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76a3dba1 name:ZooKeeperConnection Watcher:127.0.0.1:33327/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 69195 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 69195 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 69195 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 69196 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 69197 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 69197 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 69197 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 69198 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 69198 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 69198 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 69199 INFO  (zkCallback-70-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 69199 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 69200 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 69200 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 69200 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 69200 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 69202 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33327 33327
   [junit4]   2> 84224 INFO  (Thread-141) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33327 33327
   [junit4]   2> 84225 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[865631A09DC9C22E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 84226 INFO  (SUITE-ZkStateReaderTest-seed#[865631A09DC9C22E]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_865631A09DC9C22E-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=812, maxMBSortInHeap=5.615029170316947, sim=ClassicSimilarity, locale=cs, timezone=Asia/Tehran
   [junit4]   2> NOTE: Linux 4.4.0-21-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=333643136,total=508887040
   [junit4]   2> NOTE: All tests run in this JVM: [RequestLoggingTest, DocValuesMultiTest, TestSolrDeletionPolicy1, TestSSLRandomization, TestCoreDiscovery, ZkStateReaderTest]
   [junit4] Completed [15/584 (1!)] on J2 in 61.31s, 6 tests, 1 error <<< FAILURES!

[...truncated 1856 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/solr/common-build.xml:527: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/lucene/common-build.xml:1457: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.0-Linux/lucene/common-build.xml:1014: There were test failures: 584 suites (10 ignored), 2340 tests, 1 error, 114 ignored (70 assumptions) [seed: 865631A09DC9C22E]

Total time: 68 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



Mime
View raw message