Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/828/
Java: 64bit/jdk-9-ea+121 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED:
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Stack Trace:
java.util.concurrent.TimeoutException
at
__randomizedtesting.SeedInfo.seed([8B59EC9415139483:F4C75B117C71B909]:0)
at
org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
at
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
at
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
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 12105 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_8B59EC9415139483-001/init-core-data-001
[junit4] 2> 1496186 INFO
(SUITE-ZkStateReaderTest-seed#[8B59EC9415139483]-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> 1496191 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1496191 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1496191 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1496191 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1496291 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:46719
[junit4] 2> 1496291 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1496291 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1496292 INFO (zkCallback-20798-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@68acd97f
name:ZooKeeperConnection Watcher:127.0.0.1:46719 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1496292 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1496293 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1496293 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1496293 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1496293 INFO (zkCallback-20799-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@28298974
name:ZooKeeperConnection Watcher:127.0.0.1:46719 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1496293 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1496294 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1496294 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1496307 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1496307 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1496308 INFO (zkCallback-20800-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@177df463
name:ZooKeeperConnection Watcher:127.0.0.1:46719/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1496308 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1496308 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1496308 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1496309 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1496309 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1496309 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1496310 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1496310 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1496310 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1496311 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1496311 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1496311 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1496318 INFO (zkCallback-20800-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1496318 INFO (zkCallback-20800-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> 1496319 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1496319 INFO (zkCallback-20800-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> 1496319 INFO (zkCallback-20800-thread-1) [ ]
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1496319 INFO (zkCallback-20800-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> 1496320 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46719 46719
[junit4] 2> 1501333 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:46719 46719
[junit4] 2> 1501333 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1501335 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1501335 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1501335 INFO (Thread-4194) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1501335 INFO (Thread-4194) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1501435 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:36513
[junit4] 2> 1501435 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501435 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501436 INFO (zkCallback-20802-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4c2833a4
name:ZooKeeperConnection Watcher:127.0.0.1:36513 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501436 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501436 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501437 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501437 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501437 INFO (zkCallback-20803-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@428be70 name:ZooKeeperConnection
Watcher:127.0.0.1:36513 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1501437 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501437 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501437 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1501439 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501440 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501440 INFO (zkCallback-20804-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@20ccca8 name:ZooKeeperConnection
Watcher:127.0.0.1:36513/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1501440 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501440 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501440 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1501441 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1501441 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1501441 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1501442 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1501442 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1501442 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1501442 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1501443 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1501443 INFO (zkCallback-20804-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1501443 INFO (zkCallback-20804-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> 1501444 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1501444 INFO (zkCallback-20804-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> 1501444 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36513 36513
[junit4] 2> 1501574 INFO (Thread-4194) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36513 36513
[junit4] 2> 1501575 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1501576 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 1501576 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1501576 INFO (Thread-4195) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1501576 INFO (Thread-4195) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1501676 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:38663
[junit4] 2> 1501677 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501677 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501678 INFO (zkCallback-20806-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6ba78b85
name:ZooKeeperConnection Watcher:127.0.0.1:38663 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501678 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501679 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501679 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501679 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501679 INFO (zkCallback-20807-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3a49d01a
name:ZooKeeperConnection Watcher:127.0.0.1:38663 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501679 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501680 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501680 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1501680 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501681 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501681 INFO (zkCallback-20808-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@48a5e9fa
name:ZooKeeperConnection Watcher:127.0.0.1:38663/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501681 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501681 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501681 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1501682 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1501682 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1501683 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1501683 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1501683 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1501684 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1501684 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1501684 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1501685 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1501685 INFO (zkCallback-20808-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1501685 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1501686 INFO (zkCallback-20808-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> 1501686 INFO (zkCallback-20808-thread-1) [ ]
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1501687 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38663 38663
[junit4] 2> 1501719 INFO (Thread-4195) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:38663 38663
[junit4] 2> 1501719 INFO
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 1501721 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1501721 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1501721 INFO (Thread-4196) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1501721 INFO (Thread-4196) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1501821 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:36487
[junit4] 2> 1501821 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501822 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501823 INFO (zkCallback-20810-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6878f24b
name:ZooKeeperConnection Watcher:127.0.0.1:36487 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501823 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501824 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501824 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501824 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501824 INFO (zkCallback-20811-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@14af2bf0
name:ZooKeeperConnection Watcher:127.0.0.1:36487 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501824 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501825 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501825 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1501825 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501826 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501826 INFO (zkCallback-20812-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5c2f157f
name:ZooKeeperConnection Watcher:127.0.0.1:36487/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501826 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501826 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501826 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1501827 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1501827 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1501827 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1501828 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1501828 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1501828 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1501828 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1501828 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1501829 INFO (zkCallback-20812-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1501829 INFO (zkCallback-20812-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> 1501829 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1501830 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1501830 INFO (zkCallback-20812-thread-1) [ ]
o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 1501830 INFO (zkCallback-20812-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> 1501830 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1501831 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36487 36487
[junit4] 2> 1501870 INFO (Thread-4196) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36487 36487
[junit4] 2> 1501871 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1501872 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 1501873 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1501873 INFO (Thread-4197) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1501873 INFO (Thread-4197) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1501973 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:41587
[junit4] 2> 1501973 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501974 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501974 INFO (zkCallback-20814-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@52ac30eb
name:ZooKeeperConnection Watcher:127.0.0.1:41587 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501974 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501975 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501975 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501975 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501976 INFO (zkCallback-20815-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@4a44055d
name:ZooKeeperConnection Watcher:127.0.0.1:41587 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501976 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501976 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501976 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1501977 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1501977 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1501977 INFO (zkCallback-20816-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@30a68e58
name:ZooKeeperConnection Watcher:127.0.0.1:41587/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1501978 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1501978 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1501978 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1501979 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1501979 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1501980 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1501980 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1501980 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1501980 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1501981 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1501981 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1501981 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1501981 INFO (zkCallback-20816-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1501982 INFO (zkCallback-20816-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> 1501982 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1501982 INFO (zkCallback-20816-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> 1501982 INFO (zkCallback-20816-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> 1501982 INFO (zkCallback-20816-thread-1) [ ]
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1531983 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41587 41587
[junit4] 2> 1532083 INFO (Thread-4197) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:41587 41587
[junit4] 2> 1532084 INFO
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest
-Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=8B59EC9415139483
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=smn
-Dtests.timezone=Antarctica/DumontDUrville -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 30.2s J2 |
ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException
[junit4] > at
__randomizedtesting.SeedInfo.seed([8B59EC9415139483:F4C75B117C71B909]:0)
[junit4] > at
org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200)
[junit4] > at
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:126)
[junit4] > at
org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:54)
[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> 1532086 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 1532086 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1532087 INFO (Thread-4198) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1532087 INFO (Thread-4198) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1532187 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer start zk server on port:39850
[junit4] 2> 1532187 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1532187 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1532188 INFO (zkCallback-20818-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@a60f35c name:ZooKeeperConnection
Watcher:127.0.0.1:39850 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1532188 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1532188 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1532189 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1532189 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1532189 INFO (zkCallback-20819-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@3ab38fc0
name:ZooKeeperConnection Watcher:127.0.0.1:39850 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1532189 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1532190 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1532190 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1532191 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1532191 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1532191 INFO (zkCallback-20820-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@23990d04
name:ZooKeeperConnection Watcher:127.0.0.1:39850/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1532191 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1532191 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1532191 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1532192 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1532193 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1532193 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1532194 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1532194 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1532195 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1532195 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1532196 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1532196 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/c1/state.json
[junit4] 2> 1532197 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1532197 INFO (zkCallback-20820-thread-1) [ ]
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1532202 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39850 39850
[junit4] 2> 1532261 INFO (Thread-4198) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:39850 39850
[junit4] 2> 1532261 INFO
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8B59EC9415139483])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 1532262 INFO
(SUITE-ZkStateReaderTest-seed#[8B59EC9415139483]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_8B59EC9415139483-001
[junit4] 2> Jun 04, 2016 9:25:01 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 5 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene60, sim=ClassicSimilarity,
locale=smn, timezone=Antarctica/DumontDUrville
[junit4] 2> NOTE: Linux 4.4.0-21-generic amd64/Oracle Corporation 9-ea
(64-bit)/cpus=12,threads=1,free=225657864,total=515964928
[junit4] 2> NOTE: All tests run in this JVM: [TestSubQueryTransformer,
CoreAdminHandlerTest, RemoteQueryErrorTest, ScriptEngineTest,
DistributedQueryComponentOptimizationTest, FieldMutatingUpdateProcessorTest,
TestFieldTypeResource, TestMacros, TestManagedResource, TestRTimerTree,
TestStressCloudBlindAtomicUpdates, SpatialHeatmapFacetsTest,
VMParamsZkACLAndCredentialsProvidersTest, RegexBoostProcessorTest,
CollectionStateFormat2Test, TestMinMaxOnMultiValuedField, TestStressRecovery,
UpdateParamsTest, TestAuthenticationFramework, TestTrackingShardHandlerFactory,
DocExpirationUpdateProcessorFactoryTest, SimpleCollectionCreateDeleteTest,
TestPHPSerializedResponseWriter, TestCoreDiscovery, NoCacheHeaderTest,
BlockJoinFacetSimpleTest, TestFuzzyAnalyzedSuggestions,
TestExactSharedStatsCache, SampleTest, TestNonDefinedSimilarityFactory,
OverseerTest, TestRequestForwarding, CachingDirectoryFactoryTest,
OverriddenZkACLAndCredentialsProvidersTest, TestJmxIntegration,
HdfsNNFailoverTest, TestRandomCollapseQParserPlugin, SolrTestCaseJ4Test,
SearchHandlerTest, BigEndianAscendingWordDeserializerTest, TestDynamicLoading,
CircularListTest, TestSolrQueryResponse, TestCloudInspectUtil,
AtomicUpdatesTest, TestSweetSpotSimilarityFactory, TestSolrJ,
MigrateRouteKeyTest, AddBlockUpdateTest, HighlighterConfigTest,
TestSolrConfigHandlerConcurrent, TestManagedStopFilterFactory,
TestNoOpRegenerator, TestShardHandlerFactory, TestJmxMonitoredMap,
TestSolrDeletionPolicy1, TestCharFilters, TestShortCircuitedRequests,
AssignTest, CoreMergeIndexesAdminHandlerTest, TestFieldCacheSanityChecker,
TestSolrXml, ConnectionManagerTest, QueryParsingTest,
ShowFileRequestHandlerTest, StatsComponentTest, ExplicitHLLTest, TestUtils,
TestAddFieldRealTimeGet, DirectSolrConnectionTest,
FullSolrCloudDistribCmdsTest, CleanupOldIndexTest, AutoCommitTest,
DocValuesMultiTest, DistribDocExpirationUpdateProcessorTest,
LeaderElectionTest, ClusterStateTest, RecoveryZkTest, TestInitQParser,
TestBulkSchemaAPI, TestWriterPerf, TestElisionMultitermQuery, DeleteShardTest,
TestReload, TestSuggestSpellingConverter, SegmentsInfoRequestHandlerTest,
TestInitParams, BasicDistributedZk2Test, HardAutoCommitTest, TestLockTree,
DataDrivenBlockJoinTest, OverseerStatusTest, TestCollationField,
SliceStateTest, TestGraphMLResponseWriter, TestRandomRequestDistribution,
AnalyticsMergeStrategyTest, TestRandomFaceting, ZkSolrClientTest,
TestDistributedSearch, TestLazyCores, CurrencyFieldOpenExchangeTest,
CurrencyFieldXmlFileTest, AnalysisAfterCoreReloadTest,
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterTSTTest,
SuggesterTest, TestStressLucene, SuggesterWFSTTest,
WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest,
SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate,
TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest,
StatelessScriptUpdateProcessorFactoryTest, DebugComponentTest,
LukeRequestHandlerTest, TestReversedWildcardFilterFactory,
PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, TestValueSourceCache,
TermVectorComponentTest, RequiredFieldsTest, TestSolrQueryParser,
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, ReturnFieldsTest,
TestCSVResponseWriter, BinaryUpdateRequestHandlerTest, AlternateDirectoryTest,
TestSolrIndexConfig, TestMergePolicyConfig, TestBinaryField,
TestSolrCoreProperties, TestIBSimilarityFactory, ResourceLoaderTest,
PluginInfoTest, SpellingQueryConverterTest, ZkNodePropsTest, TestRTGBase,
DistributedIntervalFacetingTest, CollectionsAPISolrJTest,
LeaderFailoverAfterPartitionTest, ShardSplitTest, TestSolrCloudWithKerberosAlt,
TestStressLiveNodes, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest,
HdfsChaosMonkeyNothingIsSafeTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest,
HdfsRestartWhileUpdatingTest, HdfsThreadLeakTest,
HdfsTlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest,
StressHdfsTest, TestClusterStateMutator, ZkStateReaderTest]
[junit4] Completed [484/613 (1!)] on J2 in 36.59s, 6 tests, 1 error <<<
FAILURES!
[...truncated 411 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following error
occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:233: The following
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:531: The
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1427: The
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:984:
There were test failures: 613 suites (10 ignored), 2567 tests, 1 error, 118
ignored (72 assumptions) [seed: 8B59EC9415139483]
Total time: 51 minutes 34 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: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
