Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/16912/
Java: 32bit/jdk-9-ea+120 -client -XX:+UseConcMarkSweepGC

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

Error Message:
expected:<2> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<2> but was:<1>
        at 
__randomizedtesting.SeedInfo.seed([16188CAA6871F926:69863B2F0113D4AC]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:53)
        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:531)
        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 10588 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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_16188CAA6871F926-001/init-core-data-001
   [junit4]   2> 132274 INFO  
(SUITE-ZkStateReaderTest-seed#[16188CAA6871F926]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 132275 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 132276 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 132276 INFO  (Thread-310) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 132276 INFO  (Thread-310) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 132376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer start zk server on port:35925
   [junit4]   2> 132376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132378 INFO  (zkCallback-140-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5317af name:ZooKeeperConnection 
Watcher:127.0.0.1:35925 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132379 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132379 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132380 INFO  (zkCallback-141-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@75642b name:ZooKeeperConnection 
Watcher:127.0.0.1:35925 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132380 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132380 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132380 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 132382 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 0x15516c83b5b0001, 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> 132385 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132385 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132386 INFO  (zkCallback-142-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1684474 name:ZooKeeperConnection 
Watcher:127.0.0.1:35925/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 132386 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132387 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132387 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 132387 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 132388 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 132388 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 132389 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 132389 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 132389 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 132390 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 132391 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 132391 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 132391 INFO  (zkCallback-142-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 132392 INFO  (zkCallback-142-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> 132392 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 132393 INFO  (zkCallback-142-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> 132393 INFO  (zkCallback-142-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> 132393 INFO  (zkCallback-142-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 132395 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35925 35925
   [junit4]   2> 132441 INFO  (Thread-310) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35925 35925
   [junit4]   2> 132442 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 132444 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 132444 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 132444 INFO  (Thread-311) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 132444 INFO  (Thread-311) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 132544 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer start zk server on port:34459
   [junit4]   2> 132544 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132545 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132549 INFO  (zkCallback-144-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@c82d55 name:ZooKeeperConnection 
Watcher:127.0.0.1:34459 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132549 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132550 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132551 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132552 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132552 INFO  (zkCallback-145-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1c6e78e name:ZooKeeperConnection 
Watcher:127.0.0.1:34459 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132552 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132552 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132553 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 132554 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132554 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132555 INFO  (zkCallback-146-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1c87558 name:ZooKeeperConnection 
Watcher:127.0.0.1:34459/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 132555 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132555 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132555 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 132555 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 132556 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 132556 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 132557 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 132557 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 132557 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 132558 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 132558 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 132558 INFO  (zkCallback-146-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 132559 INFO  (zkCallback-146-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> 132559 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 132560 INFO  (zkCallback-146-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> 132561 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34459 34459
   [junit4]   2> 132743 INFO  (Thread-311) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:34459 34459
   [junit4]   2> 132744 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 132746 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 132746 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 132746 INFO  (Thread-312) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 132746 INFO  (Thread-312) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 132846 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer start zk server on port:35730
   [junit4]   2> 132846 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132847 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132848 INFO  (zkCallback-148-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@19c2751 name:ZooKeeperConnection 
Watcher:127.0.0.1:35730 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132848 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132848 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132848 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132849 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132850 INFO  (zkCallback-149-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1266ab4 name:ZooKeeperConnection 
Watcher:127.0.0.1:35730 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132850 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132850 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132850 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 132851 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132851 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132851 INFO  (zkCallback-150-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@e403c2 name:ZooKeeperConnection 
Watcher:127.0.0.1:35730/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 132851 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132852 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132852 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 132852 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 132852 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 132853 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 132853 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 132853 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 132854 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 132854 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 132854 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 132854 INFO  (zkCallback-150-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 132855 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 132855 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 132856 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35730 35730
   [junit4]   2> 132863 INFO  (Thread-312) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35730 35730
   [junit4]   2> 132863 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 132865 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 132865 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 132865 INFO  (Thread-313) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 132865 INFO  (Thread-313) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 132965 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.ZkTestServer start zk server on port:37312
   [junit4]   2> 132965 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132966 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132967 INFO  (zkCallback-152-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1f308a2 name:ZooKeeperConnection 
Watcher:127.0.0.1:37312 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132967 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132967 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132968 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132968 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132969 INFO  (zkCallback-153-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1ac3fe2 name:ZooKeeperConnection 
Watcher:127.0.0.1:37312 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 132969 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132969 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132970 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 132974 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 132974 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 132975 INFO  (zkCallback-154-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1db144e name:ZooKeeperConnection 
Watcher:127.0.0.1:37312/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 132975 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 132975 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 132975 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 132975 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 132976 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 132976 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 132977 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 132977 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 132977 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 132977 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 132978 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 132978 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 132979 INFO  (zkCallback-154-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 132979 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 132979 INFO  (zkCallback-154-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> 132979 INFO  (zkCallback-154-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 132984 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37312 37312
   [junit4]   2> 133038 INFO  (Thread-313) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37312 37312
   [junit4]   2> 133039 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[16188CAA6871F926]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 133041 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 133041 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 133042 INFO  (Thread-314) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 133042 INFO  (Thread-314) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 133142 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer start zk server on port:42385
   [junit4]   2> 133142 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133142 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133143 INFO  (zkCallback-156-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1af214 name:ZooKeeperConnection 
Watcher:127.0.0.1:42385 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 133143 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133144 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133144 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133144 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133145 INFO  (zkCallback-157-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@c338c7 name:ZooKeeperConnection 
Watcher:127.0.0.1:42385 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 133145 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133145 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133145 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 133146 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133146 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133147 INFO  (zkCallback-158-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@173f92c name:ZooKeeperConnection 
Watcher:127.0.0.1:42385/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 133147 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133147 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133147 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 133148 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 133148 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 133149 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 133149 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 133150 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 133150 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 133150 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 133151 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 133151 INFO  (zkCallback-158-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 133152 INFO  (zkCallback-158-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> 133152 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 133153 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 133153 INFO  (zkCallback-158-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Uninteresting collection c1
   [junit4]   2> 133153 INFO  (zkCallback-158-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> 133154 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 133154 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42385 42385
   [junit4]   2> 133184 INFO  (Thread-314) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:42385 42385
   [junit4]   2> 133184 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 133187 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 133187 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 133187 INFO  (Thread-315) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 133187 INFO  (Thread-315) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 133287 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer start zk server on port:41539
   [junit4]   2> 133287 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133288 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133289 INFO  (zkCallback-160-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@140a898 name:ZooKeeperConnection 
Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 133289 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133289 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133289 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133290 INFO  (zkCallback-161-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@344aa1 name:ZooKeeperConnection 
Watcher:127.0.0.1:41539 got event WatchedEvent state:SyncConnected type:None 
path:null path:null type:None
   [junit4]   2> 133290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133290 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 133291 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 133292 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 133292 INFO  (zkCallback-162-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@140cf40 name:ZooKeeperConnection 
Watcher:127.0.0.1:41539/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 133292 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 133292 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 133292 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 133293 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 133294 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 133294 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 133294 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 133295 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 133295 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 133295 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 133295 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 133296 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 133296 INFO  (zkCallback-162-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 133296 INFO  (zkCallback-162-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> 133297 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 133297 INFO  (zkCallback-162-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> 133297 INFO  (zkCallback-162-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 133299 INFO  (zkCallback-162-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> 133300 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41539 41539
   [junit4]   2> 133407 INFO  (Thread-315) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:41539 41539
   [junit4]   2> 133408 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[16188CAA6871F926])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest 
-Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=16188CAA6871F926 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=af-ZA 
-Dtests.timezone=Asia/Kamchatka -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 0.23s J1 | 
ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<2> but 
was:<1>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([16188CAA6871F926:69863B2F0113D4AC]:0)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
   [junit4]    >        at 
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:53)
   [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> 133410 INFO  
(SUITE-ZkStateReaderTest-seed#[16188CAA6871F926]-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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_16188CAA6871F926-001
   [junit4]   2> Jun 03, 2016 3:01:10 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1334, maxMBSortInHeap=5.309416303427964, 
sim=ClassicSimilarity, locale=af-ZA, timezone=Asia/Kamchatka
   [junit4]   2> NOTE: Linux 4.4.0-21-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=62601632,total=378077184
   [junit4]   2> NOTE: All tests run in this JVM: 
[SpellPossibilityIteratorTest, TestReplicaProperties, CursorMarkTest, 
PreAnalyzedUpdateProcessorTest, CSVRequestHandlerTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, ChaosMonkeySafeLeaderTest, 
BufferStoreTest, CollectionsAPIAsyncDistributedZkTest, 
LeaderInitiatedRecoveryOnShardRestartTest, TestPartialUpdateDeduplication, 
TestBlendedInfixSuggestions, NumericFieldsTest, ZkStateReaderTest]
   [junit4] Completed [28/612 (1!)] on J1 in 2.15s, 6 tests, 1 failure <<< 
FAILURES!

[...truncated 1892 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:531: 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: 612 suites (10 ignored), 2560 tests, 1 failure, 118 
ignored (72 assumptions) [seed: 16188CAA6871F926]

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