You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/05/30 21:17:42 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 236 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/236/
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Could not find collection : c1
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
at __randomizedtesting.SeedInfo.seed([E3434AF5F47C98DF:9CDDFD709D1EB555]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
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 11939 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_E3434AF5F47C98DF-001/init-core-data-001
[junit4] 2> 1667234 INFO (SUITE-ZkStateReaderTest-seed#[E3434AF5F47C98DF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 1667238 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 1667238 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1667238 INFO (Thread-2577) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1667238 INFO (Thread-2577) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1667338 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:32930
[junit4] 2> 1667338 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667339 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667340 INFO (zkCallback-1196-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@264d3265 name:ZooKeeperConnection Watcher:127.0.0.1:32930 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667340 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667341 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667342 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667347 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667347 INFO (zkCallback-1197-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d7f7ea name:ZooKeeperConnection Watcher:127.0.0.1:32930 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667347 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667347 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667347 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1667360 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667361 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667361 INFO (zkCallback-1198-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c4cc3cd name:ZooKeeperConnection Watcher:127.0.0.1:32930/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667361 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667361 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667362 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1667362 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1667362 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1667363 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1667363 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1667363 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1667364 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1667364 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1667364 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1667365 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1667366 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1667367 INFO (zkCallback-1198-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1667370 INFO (zkCallback-1198-thread-2) [ ] 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> 1667370 INFO (zkCallback-1198-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1667418 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32930 32930
[junit4] 2> 1667671 INFO (Thread-2577) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:32930 32930
[junit4] 2> 1667683 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 1667688 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 1667689 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1667699 INFO (Thread-2578) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1667699 INFO (Thread-2578) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1667809 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:36229
[junit4] 2> 1667809 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667816 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667823 INFO (zkCallback-1200-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@26c4e744 name:ZooKeeperConnection Watcher:127.0.0.1:36229 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667823 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667824 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667824 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667832 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667852 INFO (zkCallback-1201-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@77938146 name:ZooKeeperConnection Watcher:127.0.0.1:36229 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667852 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667852 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667852 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1667854 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1667855 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1667855 INFO (zkCallback-1202-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3208b425 name:ZooKeeperConnection Watcher:127.0.0.1:36229/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1667855 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1667855 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1667856 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1667856 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1667856 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1667857 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1667857 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1667857 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1667858 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1667858 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1667858 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1667859 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1667859 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1667861 INFO (zkCallback-1202-thread-2) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1667877 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36229 36229
[junit4] 2> 1668012 INFO (Thread-2578) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36229 36229
[junit4] 2> 1668013 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 1668014 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1668014 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1668015 INFO (Thread-2579) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1668015 INFO (Thread-2579) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1668115 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:58614
[junit4] 2> 1668115 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668116 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668118 INFO (zkCallback-1204-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f260781 name:ZooKeeperConnection Watcher:127.0.0.1:58614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668118 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668118 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668119 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668128 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668129 INFO (zkCallback-1205-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@155e39f8 name:ZooKeeperConnection Watcher:127.0.0.1:58614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668129 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668129 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668129 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1668130 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668131 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668134 INFO (zkCallback-1206-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@240ca5bc name:ZooKeeperConnection Watcher:127.0.0.1:58614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1668134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1668135 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1668135 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1668136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1668136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1668136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1668136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1668137 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1668138 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1668139 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$24/946922933@24354b06 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@200adb58[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 1668140 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$24/946922933@5ac4de44 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@200adb58[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 1668140 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58614 58614
[junit4] 2> 1668437 INFO (Thread-2579) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58614 58614
[junit4] 2> 1668438 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1668440 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 1668440 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1668440 INFO (Thread-2580) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1668440 INFO (Thread-2580) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1668657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:44131
[junit4] 2> 1668657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668657 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668673 INFO (zkCallback-1208-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33598e3c name:ZooKeeperConnection Watcher:127.0.0.1:44131 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668673 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668674 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668675 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668679 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668684 INFO (zkCallback-1209-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ecd1cd0 name:ZooKeeperConnection Watcher:127.0.0.1:44131 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668684 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668685 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668685 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1668693 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1668694 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1668694 INFO (zkCallback-1210-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66493ac6 name:ZooKeeperConnection Watcher:127.0.0.1:44131/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1668694 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1668695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1668695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1668695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1668696 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1668696 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1668696 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1668696 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1668697 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1668697 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1668697 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1668698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1668699 INFO (zkCallback-1210-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> 1668701 INFO (zkCallback-1210-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1668750 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1668750 INFO (zkCallback-1210-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> 1668751 INFO (zkCallback-1210-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> 1668751 WARN (zkCallback-1210-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections/c1/state.json]
[junit4] 2> 1668752 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44131 44131
[junit4] 2> 1668887 INFO (Thread-2580) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44131 44131
[junit4] 2> 1668895 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=E3434AF5F47C98DF -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=de-AT -Dtests.timezone=Mexico/BajaNorte -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.46s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([E3434AF5F47C98DF:9CDDFD709D1EB555]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1668902 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1668903 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1668903 INFO (Thread-2581) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1668903 INFO (Thread-2581) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1669003 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:51801
[junit4] 2> 1669003 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669006 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669018 INFO (zkCallback-1212-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a151a32 name:ZooKeeperConnection Watcher:127.0.0.1:51801 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669018 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669019 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669019 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669020 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669020 INFO (zkCallback-1213-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@210676f3 name:ZooKeeperConnection Watcher:127.0.0.1:51801 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669020 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669021 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669021 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1669029 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669044 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669044 INFO (zkCallback-1214-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@59b0a13e name:ZooKeeperConnection Watcher:127.0.0.1:51801/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669044 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669044 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669045 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1669045 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1669045 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1669046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1669046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1669046 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1669047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1669047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1669047 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1669055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1669057 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1669058 INFO (zkCallback-1214-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1669061 INFO (zkCallback-1214-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> 1669062 INFO (zkCallback-1214-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
[junit4] 2> 1669062 INFO (zkCallback-1214-thread-3) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1669064 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51801 51801
[junit4] 2> 1669191 INFO (Thread-2581) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51801 51801
[junit4] 2> 1669194 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1669195 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1669196 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1669198 INFO (Thread-2582) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1669198 INFO (Thread-2582) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1669298 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer start zk server on port:47991
[junit4] 2> 1669298 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669299 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669303 INFO (zkCallback-1216-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@706dfd46 name:ZooKeeperConnection Watcher:127.0.0.1:47991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669303 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669303 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669306 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669307 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669307 INFO (zkCallback-1217-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71674579 name:ZooKeeperConnection Watcher:127.0.0.1:47991 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1669310 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1669310 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1669311 INFO (zkCallback-1218-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e5cb3d4 name:ZooKeeperConnection Watcher:127.0.0.1:47991/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1669311 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1669311 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1669311 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1669311 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1669312 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1669312 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1669312 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1669313 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1669313 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1669313 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1669314 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1669315 INFO (zkCallback-1218-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> 1669321 INFO (zkCallback-1218-thread-2) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1669365 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1669365 INFO (zkCallback-1218-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> 1669367 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47991 47991
[junit4] 2> 1669771 INFO (Thread-2582) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47991 47991
[junit4] 2> 1669775 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[E3434AF5F47C98DF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1669775 INFO (SUITE-ZkStateReaderTest-seed#[E3434AF5F47C98DF]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_E3434AF5F47C98DF-001
[junit4] 2> May 30, 2016 9:03:38 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=703, maxMBSortInHeap=5.77960718905418, sim=ClassicSimilarity, locale=de-AT, timezone=Mexico/BajaNorte
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=165276208,total=499646464
[junit4] 2> NOTE: All tests run in this JVM: [HdfsRecoveryZkTest, DateFieldTest, TestUniqueKeyFieldResource, ExitableDirectoryReaderTest, ZkNodePropsTest, TestFieldCacheReopen, PathHierarchyTokenizerFactoryTest, TestReplicaProperties, BlockJoinFacetRandomTest, DocumentAnalysisRequestHandlerTest, TestPerFieldSimilarityWithDefaultOverride, TestSerializedLuceneMatchVersion, TestStressLucene, RequiredFieldsTest, SolrRequestParserTest, RuleEngineTest, AlternateDirectoryTest, BitVectorTest, SharedFSAutoReplicaFailoverTest, TestTolerantUpdateProcessorCloud, TestReqParamsAPI, TestDocSet, TriLevelCompositeIdRoutingTest, CursorMarkTest, IndexSchemaTest, TestSSLRandomization, TolerantUpdateProcessorTest, CdcrUpdateLogTest, TestFieldSortValues, RAMDirectoryFactoryTest, LeaderInitiatedRecoveryOnCommitTest, CdcrVersionReplicationTest, CollectionTooManyReplicasTest, OverseerCollectionConfigSetProcessorTest, TestPseudoReturnFields, HLLSerializationTest, TestMissingGroups, ForceLeaderTest, TestBulkSchemaConcurrent, TestSha256AuthenticationProvider, TestRemoteStreaming, DistributedSpellCheckComponentTest, TestExceedMaxTermLength, TestGeoJSONResponseWriter, CoreAdminRequestStatusTest, TestCloudPivotFacet, TestBlobHandler, TestValueSourceCache, SSLMigrationTest, HdfsWriteToMultipleCollectionsTest, TestCSVLoader, ResponseLogComponentTest, TestMiniSolrCloudClusterBase, AnalysisAfterCoreReloadTest, CacheHeaderTest, DisMaxRequestHandlerTest, AsyncCallRequestStatusResponseTest, QueryEqualityTest, SuggestComponentTest, PreAnalyzedFieldTest, TestRandomFaceting, TestFoldingMultitermQuery, FacetPivotSmallTest, TestSortByMinMaxFunction, DeleteLastCustomShardedReplicaTest, TestIBSimilarityFactory, TestRealTimeGet, SecurityConfHandlerTest, CreateCollectionCleanupTest, SolrCoreTest, DeleteStatusTest, CloudExitableDirectoryReaderTest, SortSpecParsingTest, ParsingFieldUpdateProcessorsTest, TestUseDocValuesAsStored2, TestPivotHelperCode, TestDistribDocBasedVersion, DefaultValueUpdateProcessorTest, TestSolrCloudWithKerberosAlt, AddSchemaFieldsUpdateProcessorFactoryTest, HighlighterMaxOffsetTest, BadCopyFieldTest, TestCollapseQParserPlugin, TestDistributedSearch, ExternalFileFieldSortTest, HdfsBasicDistributedZkTest, TestSearcherReuse, TestFunctionQuery, SyncSliceTest, TestStressUserVersions, CurrencyFieldOpenExchangeTest, BasicDistributedZkTest, TestRangeQuery, TestCrossCoreJoin, TestRawTransformer, DistributedQueryElevationComponentTest, TestLazyCores, CloneFieldUpdateProcessorFactoryTest, TestCloudBackupRestore, ZkStateReaderTest]
[junit4] Completed [433/612 (1!)] on J0 in 2.94s, 6 tests, 1 error <<< FAILURES!
[...truncated 587 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:531: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:984: There were test failures: 612 suites (10 ignored), 2564 tests, 1 error, 87 ignored (72 assumptions) [seed: E3434AF5F47C98DF]
Total time: 67 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any