Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.1/3/

2 tests failed.
FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
KeeperErrorCode = Session expired for /clusterstate.json

Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /clusterstate.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
        at 
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:348)
        at 
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:345)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:345)
        at 
org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:506)
        at 
org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:296)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testNoCollectionSpecified(CollectionsAPIDistributedZkTest.java:582)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:180)
        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 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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)


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([8455C9E0841D7B41:FBCB7E65ED7F56CB]: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 12641 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_8455C9E0841D7B41-001/init-core-data-001
   [junit4]   2> 4845139 INFO  
(SUITE-ZkStateReaderTest-seed#[8455C9E0841D7B41]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 4845172 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 4845172 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4845184 INFO  (Thread-13894) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4845184 INFO  (Thread-13894) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4845296 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer start zk server on port:41278
   [junit4]   2> 4845296 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4845304 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4845316 INFO  (zkCallback-6904-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5ce89999 
name:ZooKeeperConnection Watcher:127.0.0.1:41278 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4845316 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4845316 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4845317 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4845340 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4845356 INFO  (zkCallback-6905-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5eab0457 
name:ZooKeeperConnection Watcher:127.0.0.1:41278 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4845356 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4845356 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4845356 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4845358 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4845392 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4845392 INFO  (zkCallback-6906-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6bb05c07 
name:ZooKeeperConnection Watcher:127.0.0.1:41278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4845392 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4845392 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4845392 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4845393 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4845393 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4845394 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4845394 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4845394 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4845395 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4845395 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4845395 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 4845404 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4845405 INFO  (zkCallback-6906-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4845412 INFO  (zkCallback-6906-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> 4845498 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4845499 INFO  (zkCallback-6906-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> 4845500 INFO  (zkCallback-6906-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> 4845500 WARN  (zkCallback-6906-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /collections/c1/state.json]
   [junit4]   2> 4845502 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41278 41278
   [junit4]   2> 4845810 INFO  (Thread-13894) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:41278 41278
   [junit4]   2> 4845811 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest 
-Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=8455C9E0841D7B41 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=es-GT -Dtests.timezone=ART -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.67s J1 | 
ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not 
find collection : c1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([8455C9E0841D7B41:FBCB7E65ED7F56CB]: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> 4845820 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 4845821 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4845836 INFO  (Thread-13895) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4845836 INFO  (Thread-13895) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4845965 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.ZkTestServer start zk server on port:48503
   [junit4]   2> 4845965 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4845998 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4845999 INFO  (zkCallback-6908-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@112d8550 
name:ZooKeeperConnection Watcher:127.0.0.1:48503 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4845999 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4845999 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846000 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4846001 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4846001 INFO  (zkCallback-6909-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@225bec38 
name:ZooKeeperConnection Watcher:127.0.0.1:48503 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4846001 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4846001 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846001 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4846006 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4846007 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4846007 INFO  (zkCallback-6910-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6cec4125 
name:ZooKeeperConnection Watcher:127.0.0.1:48503/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4846007 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4846007 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846007 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4846008 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4846016 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4846016 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4846017 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4846017 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4846017 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4846018 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4846018 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 4846028 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4846029 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4846032 INFO  (zkCallback-6910-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4846038 INFO  (zkCallback-6910-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> 4846042 INFO  (zkCallback-6910-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 4846080 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48503 48503
   [junit4]   2> 4846310 INFO  (Thread-13895) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:48503 48503
   [junit4]   2> 4846311 INFO  
(TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[8455C9E0841D7B41]) 
[    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 4846313 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 4846313 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4846315 INFO  (Thread-13896) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4846315 INFO  (Thread-13896) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4846415 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer start zk server on port:35592
   [junit4]   2> 4846415 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4846422 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4846522 INFO  (zkCallback-6912-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@44fd3599 
name:ZooKeeperConnection Watcher:127.0.0.1:35592 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4846522 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4846523 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846524 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4846526 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4846528 INFO  (zkCallback-6913-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@244cc948 
name:ZooKeeperConnection Watcher:127.0.0.1:35592 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4846528 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4846528 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846528 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4846530 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4846531 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4846533 INFO  (zkCallback-6914-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4146b6a6 
name:ZooKeeperConnection Watcher:127.0.0.1:35592/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4846533 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4846533 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4846533 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4846534 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4846534 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4846534 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4846535 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4846535 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4846535 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4846536 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4846536 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 4846539 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4846539 INFO  (zkCallback-6914-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4846540 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4846541 INFO  (zkCallback-6914-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> 4846541 INFO  (zkCallback-6914-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 4846541 INFO  (zkCallback-6914-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> 4846542 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35592 35592
   [junit4]   2> 4846848 INFO  (Thread-13896) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35592 35592
   [junit4]   2> 4846849 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 4846851 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 4846851 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4846863 INFO  (Thread-13897) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4846863 INFO  (Thread-13897) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4847032 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer start zk server on port:48040
   [junit4]   2> 4847032 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847040 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847042 INFO  (zkCallback-6916-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4ca5b7f7 
name:ZooKeeperConnection Watcher:127.0.0.1:48040 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847042 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847043 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847043 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847044 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847044 INFO  (zkCallback-6917-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6123a6f8 
name:ZooKeeperConnection Watcher:127.0.0.1:48040 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847044 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847044 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847044 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4847051 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847052 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847052 INFO  (zkCallback-6918-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@5cd1039a 
name:ZooKeeperConnection Watcher:127.0.0.1:48040/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847052 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847052 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847052 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4847053 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4847053 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4847053 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4847054 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4847054 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4847054 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4847055 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4847055 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4847056 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4847056 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 4847056 INFO  (zkCallback-6918-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4847064 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:48040 48040
   [junit4]   2> 4847212 INFO  (Thread-13897) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:48040 48040
   [junit4]   2> 4847213 INFO  
(TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 4847215 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 4847216 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4847219 INFO  (Thread-13898) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4847219 INFO  (Thread-13898) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4847319 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer start zk server on port:37052
   [junit4]   2> 4847319 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847333 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847344 INFO  (zkCallback-6920-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@51ea6c6b 
name:ZooKeeperConnection Watcher:127.0.0.1:37052 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847344 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847344 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847345 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847353 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847356 INFO  (zkCallback-6921-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@3105accf 
name:ZooKeeperConnection Watcher:127.0.0.1:37052 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847356 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847357 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847357 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4847358 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847360 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847376 INFO  (zkCallback-6922-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@27df62d name:ZooKeeperConnection 
Watcher:127.0.0.1:37052/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 4847376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847376 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4847377 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4847377 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4847377 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4847378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4847378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4847378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4847378 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4847379 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4847380 INFO  (zkCallback-6922-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> 4847381 INFO  (zkCallback-6922-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4847430 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4847430 INFO  (zkCallback-6922-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> 4847432 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37052 37052
   [junit4]   2> 4847698 INFO  (Thread-13898) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37052 37052
   [junit4]   2> 4847702 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 4847705 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 4847706 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4847725 INFO  (Thread-13899) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4847725 INFO  (Thread-13899) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4847824 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer start zk server on port:39783
   [junit4]   2> 4847824 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847836 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847858 INFO  (zkCallback-6924-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7c28f4a6 
name:ZooKeeperConnection Watcher:127.0.0.1:39783 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847858 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847858 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847859 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847863 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847863 INFO  (zkCallback-6925-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@659bc53e 
name:ZooKeeperConnection Watcher:127.0.0.1:39783 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847863 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847863 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847863 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4847864 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4847865 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4847866 INFO  (zkCallback-6926-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4086522a 
name:ZooKeeperConnection Watcher:127.0.0.1:39783/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4847866 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4847866 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4847866 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4847867 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 4847867 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4847868 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 4847869 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4847869 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 4847869 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 4847870 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 4847870 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 4847871 INFO  (zkCallback-6926-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected 
type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 4847872 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/c1/state.json
   [junit4]   2> 4847876 INFO  (zkCallback-6926-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> 4847876 WARN  (zkCallback-6926-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /clusterstate.json]
   [junit4]   2> 4847877 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39783 39783
   [junit4]   2> 4848178 INFO  (Thread-13899) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:39783 39783
   [junit4]   2> 4848179 INFO  
(TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[8455C9E0841D7B41])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending 
testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 4848180 INFO  
(SUITE-ZkStateReaderTest-seed#[8455C9E0841D7B41]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J1/temp/solr.cloud.overseer.ZkStateReaderTest_8455C9E0841D7B41-001
   [junit4]   2> Jun 12, 2016 7:47:48 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, 
docValues:{}, maxPointsInLeafNode=1683, maxMBSortInHeap=6.646473752629392, 
sim=ClassicSimilarity, locale=es-GT, timezone=ART
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_74 (64-bit)/cpus=4,threads=1,free=41369720,total=529006592
   [junit4]   2> NOTE: All tests run in this JVM: [TestInfoStreamLogging, 
TestMaxScoreQueryParser, UniqFieldsUpdateProcessorFactoryTest, 
CdcrReplicationHandlerTest, TestDefaultStatsCache, CSVRequestHandlerTest, 
TestSolrCLIRunExample, TestDownShardTolerantSearch, CheckHdfsIndexTest, 
TestSimpleTrackingShardHandler, LoggingHandlerTest, ZkSolrClientTest, 
TestConfigSets, TestBlobHandler, RestartWhileUpdatingTest, MBeansHandlerTest, 
TermVectorComponentTest, TestMiniSolrCloudClusterBase, HdfsDirectoryTest, 
SolrXmlInZkTest, TestDFRSimilarityFactory, TestDocumentBuilder, BlockCacheTest, 
BlockJoinFacetDistribTest, FacetPivotSmallTest, CollectionReloadTest, 
CollectionsAPIAsyncDistributedZkTest, TestPartialUpdateDeduplication, 
SpellPossibilityIteratorTest, DistributedExpandComponentTest, 
SharedFSAutoReplicaFailoverUtilsTest, TestGraphTermsQParserPlugin, 
TestWordDelimiterFilterFactory, TestSolrQueryParserResource, TestTrie, 
TestSolrCloudWithKerberosAlt, TestSolrConfigHandlerCloud, 
TestFieldCacheWithThreads, HdfsRecoveryZkTest, SolrInfoMBeanTest, 
TestSSLRandomization, MinimalSchemaTest, HighlighterMaxOffsetTest, 
TestFieldCache, TestSchemaVersionResource, SpatialRPTFieldTypeTest, 
TestUseDocValuesAsStored, ChaosMonkeyNothingIsSafeTest, TestConfigOverlay, 
AsyncMigrateRouteKeyTest, WordBreakSolrSpellCheckerTest, SolrIndexSplitterTest, 
DistanceFunctionTest, TestLMJelinekMercerSimilarityFactory, SortByFunctionTest, 
TestCoreContainer, TestOnReconnectListenerSupport, 
TestEmbeddedSolrServerConstructors, LeaderInitiatedRecoveryOnShardRestartTest, 
TestFastLRUCache, TestDocBasedVersionConstraints, RankQueryTest, 
TestTolerantUpdateProcessorCloud, SolrCmdDistributorTest, OverseerRolesTest, 
TestManagedSynonymFilterFactory, ZkCLITest, TestExactStatsCache, 
UpdateRequestProcessorFactoryTest, TestPerFieldSimilarityClassic, TestUpdate, 
TestSmileRequest, MultiTermTest, ExitableDirectoryReaderTest, 
TermsComponentTest, TestOrdValues, TestClassicSimilarityFactory, 
SimplePostToolTest, ShardRoutingCustomTest, TestSolrCoreProperties, 
SpellCheckComponentTest, TestNumericTerms64, SaslZkACLProviderTest, 
TestCustomDocTransformer, ShardRoutingTest, ClusterStateUpdateTest, 
NumericFieldsTest, FullHLLTest, SuggesterFSTTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, ConvertedLegacyTest, 
AnalyticsQueryTest, DocValuesTest, TestManagedSchema, AliasIntegrationTest, 
RuleEngineTest, TestStressVersions, DeleteReplicaTest, TestCollectionAPI, 
ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, 
CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, 
FullSolrCloudDistribCmdsTest, TestDistributedGrouping, TestFaceting, 
TestRecovery, TestHashPartitioner, ZkControllerTest, TestRealTimeGet, 
TestStressReorder, HardAutoCommitTest, DistributedTermsComponentTest, 
SimpleFacetsTest, SolrCoreTest, TestGroupingSearch, 
QueryElevationComponentTest, BadIndexSchemaTest, TestSort, TestFiltering, 
BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, 
HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, 
DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, 
SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, 
SolrRequestParserTest, TestFoldingMultitermQuery, SpatialFilterTest, 
TestCSVLoader, PolyFieldTest, SchemaVersionSpecificBehaviorTest, 
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, 
DirectUpdateHandlerOptimizeTest, DocValuesMultiTest, TestSolrDeletionPolicy1, 
IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, 
TestQueryUtils, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, 
TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, 
XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, 
TestIndexingPerformance, MoreLikeThisHandlerTest, TestSolrQueryParser, 
FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, 
RegexBoostProcessorTest, SolrPluginUtilsTest, TestJmxIntegration, 
TestCollationField, ReturnFieldsTest, JSONWriterTest, TestCSVResponseWriter, 
JsonLoaderTest, TestBinaryResponseWriter, TestLFUCache, SOLR749Test, 
UpdateParamsTest, TestSolrIndexConfig, ResponseLogComponentTest, 
BadComponentTest, TestSolrDeletionPolicy2, TestFuzzyAnalyzedSuggestions, 
ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, 
TestPhraseSuggestions, TestXIncludeConfig, TestIBSimilarityFactory, 
TestBM25SimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, 
DateMathParserTest, SpellingQueryConverterTest, ClusterStateTest, TestUtils, 
SystemInfoHandlerTest, FileUtilsTest, DistributedMLTComponentTest, 
SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, 
TestTolerantSearch, CdcrReplicationDistributedZkTest, TestRequestForwarding, 
TestStressCloudBlindAtomicUpdates, TestClusterStateMutator, ZkStateReaderTest]
   [junit4] Completed [487/614 (1!)] on J1 in 3.33s, 6 tests, 1 error <<< 
FAILURES!

[...truncated 136 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/init-core-data-001
   [junit4]   2> 4554540 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 4554540 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /z/k
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 4554572 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried 
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 4554576 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4554577 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 4554585 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs
 to ./temp/Jetty_localhost_49164_hdfs____.xzk2to/webapp
   [junit4]   2> 4554662 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4554981 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:49164
   [junit4]   2> 4555030 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4555031 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 4555042 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_36638_datanode____psfc1r/webapp
   [junit4]   2> 4555128 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4555339 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36638
   [junit4]   2> 4555379 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 4555379 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 4555389 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_58341_datanode____4h2z84/webapp
   [junit4]   2> 4555428 INFO  (IPC Server handler 3 on 55405) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-63ea7d31-dada-4dd8-8098-bea0ea8c5389 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=a9a3e5ba-f84e-464c-ad1d-330687300f9b, infoPort=36638, 
ipcPort=54002, storageInfo=lv=-56;cid=testClusterID;nsid=1145587271;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 4555428 INFO  (IPC Server handler 3 on 55405) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-693b5586-6e39-4191-84f3-a764dcc4a251 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=a9a3e5ba-f84e-464c-ad1d-330687300f9b, infoPort=36638, 
ipcPort=54002, storageInfo=lv=-56;cid=testClusterID;nsid=1145587271;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 4555484 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 4555692 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58341
   [junit4]   2> 4555764 INFO  (IPC Server handler 8 on 55405) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-39d2105d-1a65-4d14-971c-4f11ec3fa6fd node DatanodeRegistration(127.0.0.1, 
datanodeUuid=51457e0b-00cc-4e46-a8ab-c9e0b7128ca6, infoPort=58341, 
ipcPort=49451, storageInfo=lv=-56;cid=testClusterID;nsid=1145587271;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 4555764 INFO  (IPC Server handler 8 on 55405) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-b05b03ca-ae2b-4698-b67f-6eef68626504 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=51457e0b-00cc-4e46-a8ab-c9e0b7128ca6, infoPort=58341, 
ipcPort=49451, storageInfo=lv=-56;cid=testClusterID;nsid=1145587271;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 4555841 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 4555842 INFO  (Thread-73006) [    ] o.a.s.c.ZkTestServer 
client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 4555842 INFO  (Thread-73006) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 4555942 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:39394
   [junit4]   2> 4555942 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4555942 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4555943 INFO  (zkCallback-25147-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7448b858 
name:ZooKeeperConnection Watcher:127.0.0.1:39394 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4555943 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4555944 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4555944 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 4555945 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4555946 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4555946 INFO  (zkCallback-25148-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@30f8f4b4 
name:ZooKeeperConnection Watcher:127.0.0.1:39394/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4555946 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4555946 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4555946 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 4555947 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 4555948 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 4555948 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 4555949 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 4555949 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 4555950 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 4555951 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 4555951 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 4555952 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 4555952 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 4555952 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 4555953 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 4555953 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 4555954 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 4555954 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 4555955 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 4555955 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 4555956 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 4555956 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 4555957 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 4555957 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 4555958 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 4555958 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 4555959 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 4555959 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 4555960 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4555960 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4555961 INFO  (zkCallback-25150-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1906c32b 
name:ZooKeeperConnection Watcher:127.0.0.1:39394/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4555961 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4555961 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4556029 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/cores/collection1
   [junit4]   2> 4556031 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 4556032 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6957e85d{/z/k,null,AVAILABLE}
   [junit4]   2> 4556033 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.e.j.s.ServerConnector Started ServerConnector@40143c5d{SSL,[ssl, 
http/1.1]}{127.0.0.1:41045}
   [junit4]   2> 4556033 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.e.j.s.Server Started @4559717ms
   [junit4]   2> 4556034 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=hdfs://localhost:55405/hdfs__localhost_55405__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.1_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001_tempDir-002_control_data,
 hostContext=/z/k, hostPort=41045, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/cores}
   [junit4]   2> 4556034 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 4556034 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001'
   [junit4]   2> 4556034 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 4556034 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 4556035 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4556035 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4556035 INFO  (zkCallback-25151-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@31bbb93f 
name:ZooKeeperConnection Watcher:127.0.0.1:39394/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4556035 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4556036 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4556036 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 4556036 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/solr.xml
   [junit4]   2> 4556039 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/cores
   [junit4]   2> 4556040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1060737855
   [junit4]   2> 4556040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001]
   [junit4]   2> 4556040 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001/control-001/lib
   [junit4]   2> 4556040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39394/solr
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 4556041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 4556042 INFO  (zkCallback-25154-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1f2efa13 
name:ZooKeeperConnection Watcher:127.0.0.1:39394 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 4556042 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 4556042 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 4556043 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.ZkController Added new OnReconnect 
listener org.apache.solr.cloud.ZkController$$Lambda$45/517706711@7302ec33
   [junit4]   2> 4556043 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 4556044 INFO  
(zkCallback-25155-thread-1-processing-n:127.0.0.1:41045_z%2Fk) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1c95c15 name:ZooKeeperConnection 
Watcher:127.0.0.1:39394/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 4556044 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 4556044 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 4556045 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 4556047 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 4556047 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 4556048 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 4556049 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 4556050 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 4556050 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/clusterstate.json
   [junit4]   2> 4556051 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 4556051 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 4556052 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ZkStateReader Loaded cluster 
properties: {urlScheme=https}
   [junit4]   2> 4556052 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 4556053 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 4556054 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 4556054 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 4556054 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/96057253998690309-127.0.0.1:41045_z%2Fk-n_0000000000
   [junit4]   2> 4556055 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:41045_z%2Fk
   [junit4]   2> 4556055 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/leader
   [junit4]   2> 4556055 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.Overseer Overseer 
(id=96057253998690309-127.0.0.1:41045_z%2Fk-n_0000000000) starting
   [junit4]   2> 4556056 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/queue-work
   [junit4]   2> 4556059 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.OverseerAutoReplicaFailoverThread 
Starting OverseerAutoReplicaFailoverThread 
autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 4556059 INFO  
(OverseerCollectionConfigSetProcessor-96057253998690309-127.0.0.1:41045_z%2Fk-n_0000000000)
 [n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.OverseerTaskProcessor Process current 
queue of overseer operations
   [junit4]   2> 4556059 INFO  
(OverseerStateUpdate-96057253998690309-127.0.0.1:41045_z%2Fk-n_0000000000) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.Overseer Starting to work on the main 
queue
   [junit4]   2> 4556061 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41045_z%2Fk
   [junit4]   2> 4556061 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[8455C9E0841D7B41]) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:41045_z%2Fk
   [junit4]   2> 4556061 INFO  
(OverseerStateUpdate-96057253998690309-127.0.0.1:41045_z%2Fk-n_0000000000) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (0)
   [junit4]   2> 4556062 INFO  
(zkCallback-25155-thread-1-processing-n:127.0.0.1:41045_z%2Fk) 
[n:127.0.0.1:41045_z%2Fk    ] o.a.s.c.c.ZkStateReader A live node change: 
[WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], 
has occurred - updating... (live nodes size: [0])
   [junit4]   2> 4556068 INFO  
(zkCallback-25155-thread-1-processing-n:127.0.0.1:41045_z%2Fk) 
[n:127.0.0.1:41045_z%2Fk    ] o

[...truncated too long message...]

 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2>        ... 54 more
   [junit4]   2> 4996654 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[8455C9E0841D7B41]-worker) [    
] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_8455C9E0841D7B41-001
   [junit4]   2> Jun 12, 2016 7:50:17 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 3 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): 
{other_tl1=PostingsFormat(name=LuceneFixedGap), 
range_facet_l_dv=Lucene50(blocksize=128), 
_version_=PostingsFormat(name=LuceneFixedGap), multiDefault=FST50, 
a_t=PostingsFormat(name=LuceneFixedGap), 
intDefault=PostingsFormat(name=LuceneFixedGap), 
a_i1=PostingsFormat(name=LuceneFixedGap), id=Lucene50(blocksize=128), 
range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), 
text=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_l=PostingsFormat(name=LuceneFixedGap), 
timestamp=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1838, 
maxMBSortInHeap=5.390002033469745, sim=ClassicSimilarity, locale=es-DO, 
timezone=Africa/Algiers
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_74 (64-bit)/cpus=4,threads=2,free=94079768,total=461373440
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSetProperties, 
QueryEqualityTest, AtomicUpdatesTest, AsyncCallRequestStatusResponseTest, 
TestValueSourceCache, DocumentAnalysisRequestHandlerTest, PrimUtilsTest, 
TestLeaderElectionZkExpiry, JavabinLoaderTest, TestJoin, 
TestReplicationHandler, HdfsSyncSliceTest, TestBadConfig, 
PingRequestHandlerTest, ReplicationFactorTest, TestReplicationHandlerBackup, 
DistributedSpellCheckComponentTest, TestRebalanceLeaders, CacheHeaderTest, 
TestCloudBackupRestore, TestSolrFieldCacheMBean, TestHighlightDedupGrouping, 
TestPKIAuthenticationPlugin, TestRangeQuery, DistributedFacetPivotLongTailTest, 
SmileWriterTest, CloudExitableDirectoryReaderTest, 
CollectionTooManyReplicasTest, TestHdfsUpdateLog, 
TestLMDirichletSimilarityFactory, XsltUpdateRequestHandlerTest, SyncSliceTest, 
AnalyticsMergeStrategyTest, PathHierarchyTokenizerFactoryTest, 
TestSolrQueryParserDefaultOperatorResource, TestRandomFaceting, 
TlogReplayBufferedWhileIndexingTest, StandardRequestHandlerTest, 
FileBasedSpellCheckerTest, OutputWriterTest, TestStressLiveNodes, 
EchoParamsTest, SuggestComponentContextFilterQueryTest, ResponseHeaderTest, 
TestSchemaSimilarityResource, IndexSchemaTest, RequiredFieldsTest, 
BasicDistributedZkTest, TestArbitraryIndexDir, ActionThrottleTest, 
TestFieldCacheSanityChecker, TestRandomCollapseQParserPlugin, TestStressLucene, 
TestPseudoReturnFields, TestConfig, TestRequestStatusCollectionAPI, 
TestLuceneMatchVersion, LeaderInitiatedRecoveryOnCommitTest, TestSchemaManager, 
TestFieldCacheVsDocValues, TestBinaryField, ZkNodePropsTest, BufferStoreTest, 
OverseerCollectionConfigSetProcessorTest, TestManagedStopFilterFactory, 
HdfsChaosMonkeySafeLeaderTest, TestFunctionQuery, 
DefaultValueUpdateProcessorTest, TestComponentsName, TestTrieFacet, 
TestNumericTerms32, TestFilteredDocIdSet, CurrencyFieldOpenExchangeTest, 
ResourceLoaderTest, DistributedSuggestComponentTest, TestRTGBase, 
TestSolr4Spatial, URLClassifyProcessorTest, SharedFSAutoReplicaFailoverTest, 
TestRandomDVFaceting, DistributedFacetPivotWhiteBoxTest, 
TestAnalyzedSuggestions, SSLMigrationTest, TestMacros, TestRemoteStreaming, 
SolrCloudExampleTest, CdcrVersionReplicationTest, EnumFieldTest, 
ExplicitHLLTest, TestExactSharedStatsCache, PeerSyncTest, CursorPagingTest, 
SynonymTokenizerTest, CopyFieldTest, PreAnalyzedFieldTest, ConnectionReuseTest, 
QueryResultKeyTest, TestInitQParser, TestBulkSchemaAPI, DeleteStatusTest, 
TestConfigSetsAPIExclusivity, LeaderElectionIntegrationTest, BasicZkTest, 
RecoveryZkTest, TestDistributedSearch, HighlighterConfigTest, 
TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, 
SolrIndexConfigTest, TestMergePolicyConfig, TestDocSet, 
TestPostingsSolrHighlighter, TestCodecSupport, TestJmxMonitoredMap, 
OpenExchangeRatesOrgProviderTest, TestSystemIdResolver, DateFieldTest, 
DOMUtilTest, RAMDirectoryFactoryTest, TestLRUCache, UUIDFieldTest, 
DistributedIntervalFacetingTest, CdcrRequestHandlerTest, CleanupOldIndexTest, 
CollectionsAPISolrJTest, ConcurrentDeleteAndCreateCollectionTest, 
DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, 
DistribCursorPagingTest, DistributedQueueTest, DistributedVersionInfoTest, 
HttpPartitionTest, SimpleCollectionCreateDeleteTest, TestCloudDeleteByQuery, 
TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestCryptoKeys, 
TestExclusionRuleCollectionAccess, TestMiniSolrCloudCluster, 
TestReplicaProperties, TestTolerantUpdateProcessorRandomCloud, 
VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, 
HdfsChaosMonkeyNothingIsSafeTest, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [532/614 (2!)] on J0 in 451.95s, 1 test, 1 error <<< 
FAILURES!

[...truncated 263 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build/solr-core/test/temp/junit4-J0-20160612_062657_126.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/heapdumps/java_pid26450.hprof
 ...
   [junit4] Heap dump file created [610675681 bytes in 3.222 secs]
   [junit4] <<< JVM J0: EOF ----

[...truncated 11 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:747: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:684: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/solr/common-build.xml:531:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/common-build.xml:1427:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.1/lucene/common-build.xml:984:
 There were test failures: 614 suites (10 ignored), 2580 tests, 2 errors, 33 
ignored (18 assumptions) [seed: 8455C9E0841D7B41]

Total time: 274 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to