Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17154/
Java: 64bit/jdk-9-ea+125 -XX:-UseCompressedOops -XX:+UseG1GC

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([55CC3B6BD49551B7:4A764A9C04F59772]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:43)
        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native 
Method)
        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
        at 
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:533)
        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(java.base@9-ea/Thread.java:843)




Build Log:
[...truncated 10928 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_55CC3B6BD49551B7-001/init-core-data-001
   [junit4]   2> 355767 INFO  
(SUITE-ZkStateReaderTest-seed#[55CC3B6BD49551B7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 355768 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 355768 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 355768 INFO  (Thread-1361) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 355768 INFO  (Thread-1361) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 355868 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.ZkTestServer start zk server on port:34868
   [junit4]   2> 355869 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 355869 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 355872 INFO  (zkCallback-444-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@52ed3db name:ZooKeeperConnection 
Watcher:127.0.0.1:34868 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 355872 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 355873 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 355873 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 0x155ba0eb1690000, 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(java.base@9-ea/Thread.java:843)
   [junit4]   2> 355874 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 355882 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 355883 INFO  (zkCallback-445-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3e74bedc 
name:ZooKeeperConnection Watcher:127.0.0.1:34868 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 355883 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 355883 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 355883 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 355885 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 355886 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 355887 INFO  (zkCallback-446-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5f8f0dc2 
name:ZooKeeperConnection Watcher:127.0.0.1:34868/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 355887 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 355887 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 355887 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 355888 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 355888 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 355888 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 355889 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 355889 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 355889 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 355889 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 355891 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 355891 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 355892 INFO  (zkCallback-446-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 355893 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 355893 INFO  (zkCallback-446-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> 355893 INFO  (zkCallback-446-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 355899 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34868 34868
   [junit4]   2> 355921 INFO  (Thread-1361) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:34868 34868
   [junit4]   2> 355921 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[55CC3B6BD49551B7]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 355923 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 355923 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 355924 INFO  (Thread-1362) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 355924 INFO  (Thread-1362) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 356024 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer start zk server on port:33583
   [junit4]   2> 356024 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356024 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356025 INFO  (zkCallback-448-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@61da577c 
name:ZooKeeperConnection Watcher:127.0.0.1:33583 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356026 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356026 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356026 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356027 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356027 INFO  (zkCallback-449-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@18c2a43b 
name:ZooKeeperConnection Watcher:127.0.0.1:33583 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356027 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356028 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356028 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 356029 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356029 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356030 INFO  (zkCallback-450-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7943e1ff 
name:ZooKeeperConnection Watcher:127.0.0.1:33583/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356030 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356030 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356030 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 356031 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 356031 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 356032 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 356032 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 356033 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 356033 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 356033 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 356034 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 356035 INFO  (zkCallback-450-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 356035 INFO  (zkCallback-450-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> 356036 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 356036 INFO  (zkCallback-450-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> 356037 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33583 33583
   [junit4]   2> 356137 INFO  (Thread-1362) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:33583 33583
   [junit4]   2> 356138 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 356139 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 356140 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 356140 INFO  (Thread-1363) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 356140 INFO  (Thread-1363) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 356240 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer start zk server on port:39251
   [junit4]   2> 356240 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356241 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356243 INFO  (zkCallback-452-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@60ee109f 
name:ZooKeeperConnection Watcher:127.0.0.1:39251 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356243 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356243 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356245 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356246 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356246 INFO  (zkCallback-453-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@723a78f5 
name:ZooKeeperConnection Watcher:127.0.0.1:39251 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356247 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356247 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356247 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 356248 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356248 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356249 INFO  (zkCallback-454-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@395135d name:ZooKeeperConnection 
Watcher:127.0.0.1:39251/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 356249 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356250 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356250 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 356251 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 356252 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 356252 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 356253 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 356253 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 356253 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 356254 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 356254 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 356255 INFO  (zkCallback-454-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 356255 INFO  (zkCallback-454-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> 356256 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 356256 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 356257 INFO  (zkCallback-454-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Uninteresting collection c1
   [junit4]   2> 356257 INFO  (zkCallback-454-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> 356258 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 356259 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39251 39251
   [junit4]   2> 356281 INFO  (Thread-1363) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:39251 39251
   [junit4]   2> 356281 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 356283 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 356283 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 356283 INFO  (Thread-1364) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 356283 INFO  (Thread-1364) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 356383 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer start zk server on port:42981
   [junit4]   2> 356383 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356384 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356385 INFO  (zkCallback-456-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6ab87a99 
name:ZooKeeperConnection Watcher:127.0.0.1:42981 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356385 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356385 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356386 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356387 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356390 INFO  (zkCallback-457-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6450c932 
name:ZooKeeperConnection Watcher:127.0.0.1:42981 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356390 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356390 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356390 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 356391 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356396 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356398 INFO  (zkCallback-458-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@62cd2328 
name:ZooKeeperConnection Watcher:127.0.0.1:42981/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356398 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356399 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356401 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 356402 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 356403 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 356403 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 356404 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 356404 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 356405 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 356405 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 356411 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 356414 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 356417 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 356419 INFO  (zkCallback-458-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 356419 INFO  (zkCallback-458-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> 356426 INFO  (zkCallback-458-thread-3) [    ] 
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> 356426 INFO  (zkCallback-458-thread-3) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 356428 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42981 42981
   [junit4]   2> 356456 INFO  (Thread-1364) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:42981 42981
   [junit4]   2> 356457 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest 
-Dtests.method=testStateFormatUpdateWithExplicitRefresh 
-Dtests.seed=55CC3B6BD49551B7 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=rn-BI -Dtests.timezone=Etc/GMT-14 -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.18s J0 | 
ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
find collection : c1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([55CC3B6BD49551B7:4A764A9C04F59772]:0)
   [junit4]    >        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:43)
   [junit4]    >        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native 
Method)
   [junit4]    >        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 356463 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 356464 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 356464 INFO  (Thread-1365) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 356464 INFO  (Thread-1365) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 356564 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer start zk server on port:44671
   [junit4]   2> 356564 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356566 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356570 INFO  (zkCallback-460-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7e589aab 
name:ZooKeeperConnection Watcher:127.0.0.1:44671 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356570 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356570 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356574 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356575 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356577 INFO  (zkCallback-461-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@344597bc 
name:ZooKeeperConnection Watcher:127.0.0.1:44671 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356577 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356577 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356577 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 356579 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356580 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356581 INFO  (zkCallback-462-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@368b2441 
name:ZooKeeperConnection Watcher:127.0.0.1:44671/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356582 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356582 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356583 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 356584 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 356585 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 356587 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 356588 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 356588 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 356589 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 356589 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 356590 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 356590 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 356591 INFO  (zkCallback-462-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 356614 INFO  (zkCallback-462-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> 356615 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 356615 INFO  (zkCallback-462-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> 356615 INFO  (zkCallback-462-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 356615 INFO  (zkCallback-462-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> 356617 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44671 44671
   [junit4]   2> 356842 INFO  (Thread-1365) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:44671 44671
   [junit4]   2> 356843 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> 356844 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 356845 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 356845 INFO  (Thread-1366) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 356845 INFO  (Thread-1366) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 356945 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer start zk server on port:36235
   [junit4]   2> 356945 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356946 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356950 INFO  (zkCallback-464-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1fca5325 
name:ZooKeeperConnection Watcher:127.0.0.1:36235 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356950 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356951 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356951 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356952 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356952 INFO  (zkCallback-465-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2e28df55 
name:ZooKeeperConnection Watcher:127.0.0.1:36235 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356952 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356953 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356953 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 356954 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 356955 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 356955 INFO  (zkCallback-466-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1defc22e 
name:ZooKeeperConnection Watcher:127.0.0.1:36235/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 356955 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 356955 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 356955 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 356956 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 356957 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 356957 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 356958 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 356959 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 356959 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 356959 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 356960 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 356961 INFO  (zkCallback-466-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 356961 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 356962 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 356963 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36235 36235
   [junit4]   2> 356984 INFO  (Thread-1366) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:36235 36235
   [junit4]   2> 356985 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[55CC3B6BD49551B7])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 356986 INFO  
(SUITE-ZkStateReaderTest-seed#[55CC3B6BD49551B7]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_55CC3B6BD49551B7-001
   [junit4]   2> Jul 05, 2016 7:56:14 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 4 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1764, maxMBSortInHeap=5.904208523378417, 
sim=ClassicSimilarity, locale=rn-BI, timezone=Etc/GMT-14
   [junit4]   2> NOTE: Linux 4.4.0-28-generic amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=12,threads=1,free=311297840,total=529530880
   [junit4]   2> NOTE: All tests run in this JVM: [TestUninvertingReader, 
TestOnReconnectListenerSupport, TolerantUpdateProcessorTest, 
TestUseDocValuesAsStored, TestCustomSort, TestPhraseSuggestions, 
BasicDistributedZkTest, JsonLoaderTest, SpatialHeatmapFacetsTest, 
TestAuthorizationFramework, TestLRUStatsCache, TestFoldingMultitermQuery, 
RequestLoggingTest, IndexSchemaTest, DistributedFacetPivotLargeTest, 
TestReloadDeadlock, CdcrReplicationDistributedZkTest, TestRealTimeGet, 
AnalyticsQueryTest, TestGeoJSONResponseWriter, TestChildDocTransformer, 
CoreMergeIndexesAdminHandlerTest, TestDistributedSearch, TestRangeQuery, 
TestIntervalFaceting, TestOrdValues, SystemInfoHandlerTest, 
TestMaxScoreQueryParser, TestCloudDeleteByQuery, TestLegacyFieldCache, 
TestComponentsName, SyncSliceTest, BlockJoinFacetDistribTest, ZkStateReaderTest]
   [junit4] Completed [116/618 (1!)] on J0 in 1.99s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 1617 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:530: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1427: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: 
There were test failures: 618 suites (10 ignored), 2588 tests, 1 error, 117 
ignored (70 assumptions) [seed: 55CC3B6BD49551B7]

Total time: 55 minutes 31 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


---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to