Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/824/ Java: 32bit/jdk1.8.0_92 -client -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay Error Message: Stack Trace: java.util.concurrent.TimeoutException at __randomizedtesting.SeedInfo.seed([DC25DA3E1E422345:A3BB6DBB77200ECF]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 12184 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_DC25DA3E1E422345-001/init-core-data-001 [junit4] 2> 1507178 INFO (SUITE-ZkStateReaderTest-seed#[DC25DA3E1E422345]-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> 1507180 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched [junit4] 2> 1507181 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1507181 INFO (Thread-4188) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1507181 INFO (Thread-4188) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1507281 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:36578 [junit4] 2> 1507281 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507281 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507284 INFO (zkCallback-1942-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cf1fce name:ZooKeeperConnection Watcher:127.0.0.1:36578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507284 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507284 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507285 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507285 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507286 INFO (zkCallback-1943-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@434b41 name:ZooKeeperConnection Watcher:127.0.0.1:36578 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507286 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507287 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507287 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1507289 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507290 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507290 INFO (zkCallback-1944-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@43ed19 name:ZooKeeperConnection Watcher:127.0.0.1:36578/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507290 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507290 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507291 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1507292 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1507293 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1507294 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1507295 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1507296 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1507296 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1507297 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1507297 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1507298 INFO (zkCallback-1944-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1507299 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1507300 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1507301 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36578 36578 [junit4] 2> 1507436 INFO (Thread-4188) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36578 36578 [junit4] 2> 1507438 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched [junit4] 2> 1507440 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1507441 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1507441 INFO (Thread-4189) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1507441 INFO (Thread-4189) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1507541 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:45289 [junit4] 2> 1507541 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507542 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507544 INFO (zkCallback-1946-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a870b5 name:ZooKeeperConnection Watcher:127.0.0.1:45289 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507544 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507545 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507545 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507546 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507547 INFO (zkCallback-1947-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ad017 name:ZooKeeperConnection Watcher:127.0.0.1:45289 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507548 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507549 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507549 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1507550 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507551 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507552 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13f609f name:ZooKeeperConnection Watcher:127.0.0.1:45289/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507552 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507552 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507552 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1507553 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1507553 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1507554 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1507555 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1507556 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1507557 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1507557 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1507558 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1507558 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1507559 INFO (zkCallback-1948-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> 1507561 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1507562 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1507562 INFO (zkCallback-1948-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1 [junit4] 2> 1507562 INFO (zkCallback-1948-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> 1507563 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1507564 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45289 45289 [junit4] 2> 1507578 INFO (Thread-4189) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45289 45289 [junit4] 2> 1507580 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1507583 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1507584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1507584 INFO (Thread-4190) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1507584 INFO (Thread-4190) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1507684 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:38433 [junit4] 2> 1507684 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507685 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507687 INFO (zkCallback-1950-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b81e84 name:ZooKeeperConnection Watcher:127.0.0.1:38433 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507687 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507689 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507690 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507690 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507691 INFO (zkCallback-1951-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75a684 name:ZooKeeperConnection Watcher:127.0.0.1:38433 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507691 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507691 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507691 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1507693 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507693 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507694 INFO (zkCallback-1952-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14607b2 name:ZooKeeperConnection Watcher:127.0.0.1:38433/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507694 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1507695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1507696 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1507697 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1507697 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1507698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1507698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1507698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1507701 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1507701 INFO (zkCallback-1952-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1507702 INFO (zkCallback-1952-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> 1507703 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1507704 INFO (zkCallback-1952-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> 1507705 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38433 38433 [junit4] 2> 1507804 INFO (Thread-4190) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38433 38433 [junit4] 2> 1507805 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1507809 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay [junit4] 2> 1507809 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1507809 INFO (Thread-4191) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1507809 INFO (Thread-4191) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1507909 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:38020 [junit4] 2> 1507909 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507910 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507912 INFO (zkCallback-1954-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b54fbe name:ZooKeeperConnection Watcher:127.0.0.1:38020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507912 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507912 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507913 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507913 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507914 INFO (zkCallback-1955-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1151e0b name:ZooKeeperConnection Watcher:127.0.0.1:38020 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507914 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507915 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507915 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1507917 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1507917 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1507918 INFO (zkCallback-1956-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a348d0 name:ZooKeeperConnection Watcher:127.0.0.1:38020/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1507919 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1507919 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1507920 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1507921 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1507922 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1507922 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1507923 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1507924 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1507924 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1507925 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1507926 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1507926 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1507927 INFO (zkCallback-1956-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1507929 INFO (zkCallback-1956-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> 1507929 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1507930 INFO (zkCallback-1956-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> 1507930 INFO (zkCallback-1956-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1507931 INFO (zkCallback-1956-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> 1537932 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38020 38020 [junit4] 2> 1537950 INFO (Thread-4191) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38020 38020 [junit4] 2> 1537952 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=DC25DA3E1E422345 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=IET -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 30.1s J2 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<< [junit4] > Throwable #1: java.util.concurrent.TimeoutException [junit4] > at __randomizedtesting.SeedInfo.seed([DC25DA3E1E422345:A3BB6DBB77200ECF]: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 java.lang.Thread.run(Thread.java:745) [junit4] 2> 1537956 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation [junit4] 2> 1537956 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1537956 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1537956 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1538056 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:35713 [junit4] 2> 1538056 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538057 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538058 INFO (zkCallback-1958-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50934a name:ZooKeeperConnection Watcher:127.0.0.1:35713 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538058 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538059 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538060 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538060 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538061 INFO (zkCallback-1959-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fb9652 name:ZooKeeperConnection Watcher:127.0.0.1:35713 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538061 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538061 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538061 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1538063 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538065 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538066 INFO (zkCallback-1960-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f66feb name:ZooKeeperConnection Watcher:127.0.0.1:35713/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538066 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538066 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538067 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1538067 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1538068 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1538069 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1538069 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1538070 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1538070 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1538070 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1538071 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1538072 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1538072 INFO (zkCallback-1960-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1538073 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1538073 INFO (zkCallback-1960-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> 1538074 INFO (zkCallback-1960-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1538075 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35713 35713 [junit4] 2> 1538296 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35713 35713 [junit4] 2> 1538297 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation [junit4] 2> 1538299 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh [junit4] 2> 1538300 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1538300 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1538300 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1538400 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer start zk server on port:44993 [junit4] 2> 1538400 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538401 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538402 INFO (zkCallback-1962-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1853688 name:ZooKeeperConnection Watcher:127.0.0.1:44993 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538402 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538403 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538404 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538404 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538405 INFO (zkCallback-1963-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e68354 name:ZooKeeperConnection Watcher:127.0.0.1:44993 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538405 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538405 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538405 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1538406 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1538407 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1538408 INFO (zkCallback-1964-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@60f66 name:ZooKeeperConnection Watcher:127.0.0.1:44993/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1538408 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1538408 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1538408 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1538409 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1538410 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1538410 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1538411 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1538411 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1538411 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1538412 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1538412 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1538413 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1538413 INFO (zkCallback-1964-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1538413 INFO (zkCallback-1964-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> 1538414 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1538414 INFO (zkCallback-1964-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> 1538414 INFO (zkCallback-1964-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1538415 INFO (zkCallback-1964-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> 1538415 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44993 44993 [junit4] 2> 1538431 INFO (Thread-4193) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44993 44993 [junit4] 2> 1538432 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[DC25DA3E1E422345]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh [junit4] 2> 1538433 INFO (SUITE-ZkStateReaderTest-seed#[DC25DA3E1E422345]-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_DC25DA3E1E422345-001 [junit4] 2> Jun 04, 2016 12:23:39 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=844, maxMBSortInHeap=6.3386821505413735, sim=ClassicSimilarity, locale=vi, timezone=IET [junit4] 2> NOTE: Linux 4.4.0-21-generic i386/Oracle Corporation 1.8.0_92 (32-bit)/cpus=12,threads=1,free=84126016,total=379322368 [junit4] 2> NOTE: All tests run in this JVM: [TestLockTree, StressHdfsTest, TestJettySolrRunner, FieldAnalysisRequestHandlerTest, MergeStrategyTest, PrimitiveFieldTypeTest, DocExpirationUpdateProcessorFactoryTest, EnumFieldTest, XmlUpdateRequestHandlerTest, BlockJoinFacetSimpleTest, TestCharFilters, RegexBoostProcessorTest, TestMacros, TestMinMaxOnMultiValuedField, TestReload, TestConfigSetsAPI, TestNonDefinedSimilarityFactory, TestRestoreCore, TestRequestForwarding, TestStressCloudBlindAtomicUpdates, SimpleMLTQParserTest, TestExactSharedStatsCache, DistributedQueryComponentOptimizationTest, TestTolerantSearch, TestGroupingSearch, TestManagedStopFilterFactory, SpatialHeatmapFacetsTest, TestUtils, AssignTest, CleanupOldIndexTest, AddBlockUpdateTest, TestExtendedDismaxParser, TestJmxMonitoredMap, ExplicitHLLTest, TermVectorComponentDistributedTest, TestTrieFacet, TestSolrIndexConfig, ZkStateWriterTest, TestFastWriter, BasicDistributedZk2Test, ResponseHeaderTest, DirectUpdateHandlerTest, CollectionStateFormat2Test, TestRecovery, TestRecoveryHdfs, TestScoreJoinQPScore, BinaryUpdateRequestHandlerTest, HLLUtilTest, RecoveryZkTest, TestIndexSearcher, PluginInfoTest, TestRandomCollapseQParserPlugin, TestSolrDeletionPolicy1, ClusterStateTest, TestShortCircuitedRequests, ChangedSchemaMergeTest, TestRTimerTree, PreAnalyzedUpdateProcessorTest, SimpleFacetsTest, AutoCommitTest, BJQParserTest, DistributedVersionInfoTest, TestAuthenticationFramework, TestFuzzyAnalyzedSuggestions, UUIDUpdateProcessorFallbackTest, TestJmxIntegration, TestSolrQueryResponse, TestHdfsUpdateLog, TestSubQueryTransformer, TestPHPSerializedResponseWriter, SolrTestCaseJ4Test, DistribDocExpirationUpdateProcessorTest, TestSchemaManager, OverseerStatusTest, TestCSVResponseWriter, SuggesterWFSTTest, CircularListTest, CollectionsAPISolrJTest, DebugComponentTest, TestDefaultSearchFieldResource, LeaderElectionTest, HighlighterConfigTest, TestXIncludeConfig, BasicAuthIntegrationTest, DataDrivenBlockJoinTest, HdfsRestartWhileUpdatingTest, TestSort, SliceStateTest, TestShardHandlerFactory, TestSchemaSimilarityResource, StatsComponentTest, TestSlowCompositeReaderWrapper, TestManagedResource, AnalyticsMergeStrategyTest, NoCacheHeaderTest, BaseCdcrDistributedZkTest, StatelessScriptUpdateProcessorFactoryTest, UnloadDistributedZkTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestRealTimeGet, TestStressReorder, TestJoin, TestRangeQuery, SolrCmdDistributorTest, BadIndexSchemaTest, TestFiltering, TestLFUCache, TestCrossCoreJoin, TestDistributedMissingSort, ActionThrottleTest, CollectionTooManyReplicasTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, ForceLeaderTest, ShardSplitTest, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest] [junit4] Completed [498/613 (1!)] on J2 in 31.78s, 6 tests, 1 error <<< FAILURES! [...truncated 356 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, 116 ignored (72 assumptions) [seed: DC25DA3E1E422345] Total time: 61 minutes 40 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