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/01 22:32:56 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 243 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/243/
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([9D9AACC87084909C:E2041B4D19E6BD16]: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 12128 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_9D9AACC87084909C-001/init-core-data-001
[junit4] 2> 2006589 INFO (SUITE-ZkStateReaderTest-seed#[9D9AACC87084909C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
[junit4] 2> 2006594 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2006594 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2006594 INFO (Thread-6790) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2006594 INFO (Thread-6790) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2006694 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:56053
[junit4] 2> 2006694 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2006695 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2006698 INFO (zkCallback-2754-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2384752e name:ZooKeeperConnection Watcher:127.0.0.1:56053 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2006698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2006698 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2006699 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2006700 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2006701 INFO (zkCallback-2755-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1563b447 name:ZooKeeperConnection Watcher:127.0.0.1:56053 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2006701 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2006701 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2006701 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2006707 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2006708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2006708 INFO (zkCallback-2756-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@157d2381 name:ZooKeeperConnection Watcher:127.0.0.1:56053/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2006708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2006709 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2006709 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2006710 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2006710 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2006711 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2006712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2006712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2006712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2006713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2006713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2006714 INFO (zkCallback-2756-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2006715 INFO (zkCallback-2756-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> 2006716 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2006716 INFO (zkCallback-2756-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> 2006718 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56053 56053
[junit4] 2> 2007035 INFO (Thread-6790) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:56053 56053
[junit4] 2> 2007036 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2007040 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2007041 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2007041 INFO (Thread-6791) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2007041 INFO (Thread-6791) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2007141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:51812
[junit4] 2> 2007141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2007142 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2007144 INFO (zkCallback-2758-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ccdefe7 name:ZooKeeperConnection Watcher:127.0.0.1:51812 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2007145 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2007145 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2007146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2007146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2007147 INFO (zkCallback-2759-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30821d7d name:ZooKeeperConnection Watcher:127.0.0.1:51812 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2007147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2007147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2007147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2007150 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2007151 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2007151 INFO (zkCallback-2760-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@fea6e4f name:ZooKeeperConnection Watcher:127.0.0.1:51812/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2007151 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2007152 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2007152 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2007153 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2007154 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2007154 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2007155 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2007156 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2007156 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2007156 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2007157 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2007158 INFO (zkCallback-2760-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2007159 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2007160 INFO (zkCallback-2760-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> 2007162 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51812 51812
[junit4] 2> 2008373 INFO (Thread-6791) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51812 51812
[junit4] 2> 2008374 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2008376 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2008377 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2008377 INFO (Thread-6792) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2008377 INFO (Thread-6792) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2008477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:46143
[junit4] 2> 2008477 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008478 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008479 INFO (zkCallback-2762-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7eac2235 name:ZooKeeperConnection Watcher:127.0.0.1:46143 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008479 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008481 INFO (zkCallback-2763-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9a5736 name:ZooKeeperConnection Watcher:127.0.0.1:46143 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008481 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008481 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008481 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2008483 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008483 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008484 INFO (zkCallback-2764-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8fdd651 name:ZooKeeperConnection Watcher:127.0.0.1:46143/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008484 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008484 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008484 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2008485 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2008485 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2008486 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2008486 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2008487 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2008487 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2008488 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2008488 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2008489 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2008489 INFO (zkCallback-2764-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2008490 INFO (zkCallback-2764-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> 2008490 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2008491 INFO (zkCallback-2764-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> 2008491 INFO (zkCallback-2764-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2008491 INFO (zkCallback-2764-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> 2008492 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46143 46143
[junit4] 2> 2008762 INFO (Thread-6792) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46143 46143
[junit4] 2> 2008763 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 2008765 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 2008766 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2008766 INFO (Thread-6793) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2008766 INFO (Thread-6793) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2008866 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:57240
[junit4] 2> 2008866 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008866 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008868 INFO (zkCallback-2766-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a33d838 name:ZooKeeperConnection Watcher:127.0.0.1:57240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008868 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008868 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008868 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008869 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008869 INFO (zkCallback-2767-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17c40c18 name:ZooKeeperConnection Watcher:127.0.0.1:57240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008869 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008869 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008869 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2008871 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2008871 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2008872 INFO (zkCallback-2768-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64ce1e92 name:ZooKeeperConnection Watcher:127.0.0.1:57240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2008872 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2008872 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2008872 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2008873 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2008879 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2008880 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2008880 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2008880 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2008881 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2008881 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2008882 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2008885 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2008886 INFO (zkCallback-2768-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2008887 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2008887 INFO (zkCallback-2768-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> 2008887 INFO (zkCallback-2768-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2008938 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57240 57240
[junit4] 2> 2009144 INFO (Thread-6793) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57240 57240
[junit4] 2> 2009146 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 2009149 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 2009149 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2009150 INFO (Thread-6794) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2009150 INFO (Thread-6794) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2009250 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:48623
[junit4] 2> 2009250 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009250 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009252 INFO (zkCallback-2770-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@775dd280 name:ZooKeeperConnection Watcher:127.0.0.1:48623 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009252 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009252 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009253 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009253 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009253 INFO (zkCallback-2771-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27a3bc27 name:ZooKeeperConnection Watcher:127.0.0.1:48623 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009254 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009254 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009254 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2009255 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009256 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009256 INFO (zkCallback-2772-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1242269a name:ZooKeeperConnection Watcher:127.0.0.1:48623/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009256 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009269 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009270 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2009270 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2009271 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2009271 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2009272 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2009273 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2009273 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2009273 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2009274 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2009274 INFO (zkCallback-2772-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2009275 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2009276 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2009277 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48623 48623
[junit4] 2> 2009471 INFO (Thread-6794) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48623 48623
[junit4] 2> 2009472 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 2009475 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 2009475 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2009475 INFO (Thread-6795) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2009475 INFO (Thread-6795) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2009575 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer start zk server on port:35251
[junit4] 2> 2009575 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009576 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009577 INFO (zkCallback-2774-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24a1c53b name:ZooKeeperConnection Watcher:127.0.0.1:35251 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009577 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009578 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009578 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009578 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009579 INFO (zkCallback-2775-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65f49fa2 name:ZooKeeperConnection Watcher:127.0.0.1:35251 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009579 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009579 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009579 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2009581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2009581 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2009582 INFO (zkCallback-2776-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50294e97 name:ZooKeeperConnection Watcher:127.0.0.1:35251/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2009582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2009582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2009582 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2009583 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2009583 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2009584 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2009585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2009585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2009585 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2009586 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2009587 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2009589 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2009592 INFO (zkCallback-2776-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2009593 INFO (zkCallback-2776-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> 2009643 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2009644 INFO (zkCallback-2776-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> 2009644 INFO (zkCallback-2776-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> 2009644 INFO (zkCallback-2776-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2009645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35251 35251
[junit4] 2> 2009840 INFO (Thread-6795) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35251 35251
[junit4] 2> 2009841 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[9D9AACC87084909C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=9D9AACC87084909C -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sr-RS -Dtests.timezone=Europe/Jersey -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.37s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([9D9AACC87084909C:E2041B4D19E6BD16]: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> 2009845 INFO (SUITE-ZkStateReaderTest-seed#[9D9AACC87084909C]-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_9D9AACC87084909C-001
[junit4] 2> Jun 01, 2016 10:19:01 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=1286, maxMBSortInHeap=5.061370634273344, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=sr-RS, timezone=Europe/Jersey
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=220262968,total=519569408
[junit4] 2> NOTE: All tests run in this JVM: [FileBasedSpellCheckerTest, SystemInfoHandlerTest, ConnectionReuseTest, TestArbitraryIndexDir, SignatureUpdateProcessorFactoryTest, TestUniqueKeyFieldResource, TestManagedSchemaAPI, FacetPivotSmallTest, TestInfoStreamLogging, TestFastOutputStream, OverseerTaskQueueTest, PingRequestHandlerTest, DistributedFacetPivotSmallAdvancedTest, BitVectorTest, PolyFieldTest, TestRangeQuery, StandardRequestHandlerTest, BadCopyFieldTest, TestPhraseSuggestions, TestConfigSets, CheckHdfsIndexTest, LeaderElectionIntegrationTest, TestRemoteStreaming, TriLevelCompositeIdRoutingTest, HdfsLockFactoryTest, DocValuesMissingTest, ForceLeaderTest, DistributedQueryComponentCustomSortTest, IndexSchemaRuntimeFieldTest, TestLRUCache, BasicZkTest, URLClassifyProcessorTest, DefaultValueUpdateProcessorTest, SSLMigrationTest, TestChildDocTransformer, TestLuceneMatchVersion, TestRealTimeGet, TestSerializedLuceneMatchVersion, TestBinaryField, TestFoldingMultitermQuery, TestStressUserVersions, TestSolr4Spatial, TestReplicationHandler, TestCSVLoader, TestHighlightDedupGrouping, TestReRankQParserPlugin, TestLRUStatsCache, TestLegacyFieldCache, TestCollapseQParserPlugin, TestUninvertingReader, SuggestComponentTest, MultiThreadedOCPTest, TestStressLucene, TestTolerantUpdateProcessorCloud, RequiredFieldsTest, TestPseudoReturnFields, XsltUpdateRequestHandlerTest, DocumentAnalysisRequestHandlerTest, CdcrVersionReplicationTest, TestRandomFaceting, CursorPagingTest, TestRTGBase, CacheHeaderTest, TestSolr4Spatial2, SolrRequestParserTest, TestBadConfig, TestConfigSetsAPIExclusivity, TestUseDocValuesAsStored2, DistributedQueryElevationComponentTest, TestCloudBackupRestore, HdfsRecoveryZkTest, TestRandomDVFaceting, ResponseLogComponentTest, TestSubQueryTransformerDistrib, AnalysisAfterCoreReloadTest, TestRawTransformer, TestFieldSortValues, JsonLoaderTest, SolrCoreTest, DisMaxRequestHandlerTest, DeleteLastCustomShardedReplicaTest, DistributedExpandComponentTest, BlockJoinFacetRandomTest, DistributedSpellCheckComponentTest, TestComponentsName, SyncSliceTest, SecurityConfHandlerTest, CollectionTooManyReplicasTest, TolerantUpdateProcessorTest, CreateCollectionCleanupTest, CloudMLTQParserTest, TestBlobHandler, TestOnReconnectListenerSupport, SOLR749Test, RAMDirectoryFactoryTest, TestCrossCoreJoin, TestPerFieldSimilarityWithDefaultOverride, TestCloudSchemaless, ParsingFieldUpdateProcessorsTest, TestDynamicFieldResource, TestSolrConfigHandlerCloud, BasicDistributedZkTest, ShardRoutingTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, ReturnFieldsTest, MBeansHandlerTest, BinaryUpdateRequestHandlerTest, TestLFUCache, AssignTest, AsyncCallRequestStatusResponseTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, DeleteShardTest, DeleteStatusTest, DistributedVersionInfoTest, LeaderInitiatedRecoveryOnShardRestartTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, RemoteQueryErrorTest, SharedFSAutoReplicaFailoverTest, SolrCloudExampleTest, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestMiniSolrCloudCluster, TestReplicaProperties, TestSSLRandomization, TestSolrCloudWithKerberosAlt, HdfsChaosMonkeyNothingIsSafeTest, HdfsNNFailoverTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, ZkStateReaderTest]
[junit4] Completed [495/612 (1!)] on J0 in 4.03s, 6 tests, 1 error <<< FAILURES!
[...truncated 402 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: 9D9AACC87084909C]
Total time: 72 minutes 28 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