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/06/02 18:53:45 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 247 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/247/
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([4DE70A0D02536C9B:3279BD886B314111]: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 11476 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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_4DE70A0D02536C9B-001/init-core-data-001
[junit4] 2> 1185354 INFO (SUITE-ZkStateReaderTest-seed#[4DE70A0D02536C9B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1185356 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1185357 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1185357 INFO (Thread-2903) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1185357 INFO (Thread-2903) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1185457 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:51341
[junit4] 2> 1185457 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185457 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185459 INFO (zkCallback-1290-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d1ec159 name:ZooKeeperConnection Watcher:127.0.0.1:51341 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185459 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185459 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185460 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185464 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185464 INFO (zkCallback-1291-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ac76f56 name:ZooKeeperConnection Watcher:127.0.0.1:51341 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185464 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185465 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185465 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1185468 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185472 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185476 INFO (zkCallback-1292-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c0c7f01 name:ZooKeeperConnection Watcher:127.0.0.1:51341/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185476 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1185478 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1185478 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1185479 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1185479 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1185480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1185480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1185480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1185481 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1185481 INFO (zkCallback-1292-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1185482 INFO (zkCallback-1292-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> 1185483 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1185483 INFO (zkCallback-1292-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> 1185485 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51341 51341
[junit4] 2> 1185597 INFO (Thread-2903) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51341 51341
[junit4] 2> 1185598 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1185600 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 1185600 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1185600 INFO (Thread-2904) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1185600 INFO (Thread-2904) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1185700 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:34985
[junit4] 2> 1185700 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185701 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185702 INFO (zkCallback-1294-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d2993fd name:ZooKeeperConnection Watcher:127.0.0.1:34985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185702 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185702 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185703 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185703 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185704 INFO (zkCallback-1295-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68620b26 name:ZooKeeperConnection Watcher:127.0.0.1:34985 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185704 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185704 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185704 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1185705 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1185706 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1185706 INFO (zkCallback-1296-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3feece97 name:ZooKeeperConnection Watcher:127.0.0.1:34985/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1185706 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1185706 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1185707 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1185707 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1185708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1185708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1185709 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1185712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1185713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1185713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1185714 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1185714 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1185715 INFO (zkCallback-1296-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1185715 INFO (zkCallback-1296-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> 1185766 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1185767 INFO (zkCallback-1296-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> 1185767 INFO (zkCallback-1296-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> 1185767 INFO (zkCallback-1296-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1185768 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34985 34985
[junit4] 2> 1186028 INFO (Thread-2904) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34985 34985
[junit4] 2> 1186029 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=4DE70A0D02536C9B -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=America/Metlakatla -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.43s J1 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([4DE70A0D02536C9B:3279BD886B314111]: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> 1186032 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1186032 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1186032 INFO (Thread-2905) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1186032 INFO (Thread-2905) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1186132 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:43415
[junit4] 2> 1186132 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186133 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186134 INFO (zkCallback-1298-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@769d7285 name:ZooKeeperConnection Watcher:127.0.0.1:43415 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186134 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186135 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186135 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186136 INFO (zkCallback-1299-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@464877cd name:ZooKeeperConnection Watcher:127.0.0.1:43415 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1186138 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186138 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186139 INFO (zkCallback-1300-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57bffd59 name:ZooKeeperConnection Watcher:127.0.0.1:43415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186139 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186139 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186139 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1186140 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1186141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1186141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1186142 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1186142 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1186143 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1186143 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1186144 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1186144 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1186145 INFO (zkCallback-1300-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1186145 INFO (zkCallback-1300-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> 1186146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1186146 INFO (zkCallback-1300-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> 1186146 INFO (zkCallback-1300-thread-3) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1186147 INFO (zkCallback-1300-thread-4) [ ] 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> 1186151 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43415 43415
[junit4] 2> 1186474 INFO (Thread-2905) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43415 43415
[junit4] 2> 1186475 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1186477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1186477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1186477 INFO (Thread-2906) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1186477 INFO (Thread-2906) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1186577 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:56254
[junit4] 2> 1186577 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186578 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186580 INFO (zkCallback-1302-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@793c76cf name:ZooKeeperConnection Watcher:127.0.0.1:56254 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186580 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186580 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186582 INFO (zkCallback-1303-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e1f4e57 name:ZooKeeperConnection Watcher:127.0.0.1:56254 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1186584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1186584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1186585 INFO (zkCallback-1304-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a6eca98 name:ZooKeeperConnection Watcher:127.0.0.1:56254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1186585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1186585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1186588 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1186589 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1186589 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1186590 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1186591 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1186591 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1186591 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1186591 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1186592 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1186592 INFO (zkCallback-1304-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1186603 INFO (zkCallback-1304-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> 1186643 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1186644 INFO (zkCallback-1304-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> 1186645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56254 56254
[junit4] 2> 1186997 INFO (Thread-2906) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56254 56254
[junit4] 2> 1186998 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1187000 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 1187001 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1187001 INFO (Thread-2907) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1187001 INFO (Thread-2907) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1187101 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:45451
[junit4] 2> 1187101 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187105 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187106 INFO (zkCallback-1306-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7102afd0 name:ZooKeeperConnection Watcher:127.0.0.1:45451 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187106 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187106 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187107 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187107 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187108 INFO (zkCallback-1307-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@304be51 name:ZooKeeperConnection Watcher:127.0.0.1:45451 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187108 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187108 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187108 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1187109 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187113 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187113 INFO (zkCallback-1308-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3668f575 name:ZooKeeperConnection Watcher:127.0.0.1:45451/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187113 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187114 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187114 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1187115 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1187115 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1187116 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1187117 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1187117 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1187118 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1187118 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1187119 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1187119 INFO (zkCallback-1308-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1187124 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1187124 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1187125 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45451 45451
[junit4] 2> 1187347 INFO (Thread-2907) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45451 45451
[junit4] 2> 1187348 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 1187351 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 1187351 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1187351 INFO (Thread-2908) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1187351 INFO (Thread-2908) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1187452 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer start zk server on port:45593
[junit4] 2> 1187452 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187452 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187457 INFO (zkCallback-1310-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47ae1e05 name:ZooKeeperConnection Watcher:127.0.0.1:45593 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187457 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187457 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187461 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187461 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187462 INFO (zkCallback-1311-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c743483 name:ZooKeeperConnection Watcher:127.0.0.1:45593 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187462 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187462 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187462 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1187464 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1187464 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1187465 INFO (zkCallback-1312-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a924d56 name:ZooKeeperConnection Watcher:127.0.0.1:45593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1187465 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1187465 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1187465 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1187466 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1187467 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1187468 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1187468 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1187469 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1187469 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1187470 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1187471 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1187471 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1187471 INFO (zkCallback-1312-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1187476 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1187476 INFO (zkCallback-1312-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> 1187476 INFO (zkCallback-1312-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1187528 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45593 45593
[junit4] 2> 1187975 INFO (Thread-2908) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45593 45593
[junit4] 2> 1187977 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[4DE70A0D02536C9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 1187977 INFO (SUITE-ZkStateReaderTest-seed#[4DE70A0D02536C9B]-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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_4DE70A0D02536C9B-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=de, timezone=America/Metlakatla
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=272677800,total=530579456
[junit4] 2> NOTE: All tests run in this JVM: [TestCollapseQParserPlugin, HdfsRecoveryZkTest, TestSystemIdResolver, CollectionsAPIDistributedZkTest, TestTrie, BlockDirectoryTest, TestSha256AuthenticationProvider, BlockJoinFacetRandomTest, TestDistribIDF, HdfsCollectionsAPIDistributedZkTest, DistributedQueueTest, TestAnalyzeInfixSuggestions, DistributedIntervalFacetingTest, TestFastLRUCache, TestSearcherReuse, ZkSolrClientTest, TestLRUStatsCache, OpenExchangeRatesOrgProviderTest, FacetPivotSmallTest, DistributedMLTComponentTest, DirectSolrSpellCheckerTest, TestJsonFacets, TestFreeTextSuggestions, TestQueryTypes, TestConfigSets, OpenCloseCoreStressTest, TermVectorComponentTest, DistributedQueryComponentCustomSortTest, TestSerializedLuceneMatchVersion, TestSortByMinMaxFunction, TestSimpleTrackingShardHandler, TestQuerySenderListener, TestReRankQParserPlugin, SpellingQueryConverterTest, SaslZkACLProviderTest, CloneFieldUpdateProcessorFactoryTest, HighlighterMaxOffsetTest, SolrCmdDistributorTest, TestIntervalFaceting, TestTestInjection, DocValuesMissingTest, FastVectorHighlighterTest, TestMiniSolrCloudClusterBase, TestDocumentBuilder, MultiTermTest, CdcrReplicationHandlerTest, TestConfigOverlay, LeaderInitiatedRecoveryOnShardRestartTest, TestInfoStreamLogging, TestDistributedGrouping, TestBlobHandler, TestRestManager, CoreAdminRequestStatusTest, TestBinaryResponseWriter, DOMUtilTest, TestLegacyFieldCache, TestDFISimilarityFactory, TestFieldCacheReopen, TermsComponentTest, CSVRequestHandlerTest, TestCopyFieldCollectionResource, TestSSLRandomization, DeleteReplicaTest, CustomCollectionTest, SortSpecParsingTest, SecurityConfHandlerTest, HdfsLockFactoryTest, ClusterStateUpdateTest, FileUtilsTest, RuleEngineTest, HttpPartitionTest, TolerantUpdateProcessorTest, TestUseDocValuesAsStored2, TestSolrDynamicMBean, TestCollectionAPI, OverseerRolesTest, TestGeoJSONResponseWriter, TestCloudSchemaless, SpatialFilterTest, TestTolerantUpdateProcessorCloud, TestAuthorizationFramework, TestSchemaResource, SharedFSAutoReplicaFailoverUtilsTest, ShardRoutingTest, TestQuerySenderNoQuery, DistributedTermsComponentTest, DistribCursorPagingTest, TestLFUCache, SpellPossibilityIteratorTest, SolrIndexConfigTest, SolrXmlInZkTest, TestCursorMarkWithoutUniqueKey, DistributedFacetPivotSmallAdvancedTest, HdfsBasicDistributedZkTest, ZkStateReaderTest]
[junit4] Completed [294/613 (1!)] on J1 in 2.63s, 6 tests, 1 error <<< FAILURES!
[...truncated 1048 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: 613 suites (10 ignored), 2565 tests, 1 error, 87 ignored (72 assumptions) [seed: 4DE70A0D02536C9B]
Total time: 67 minutes 35 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