lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-5.3 - Build # 9 - Still Failing
Date Sun, 10 Jan 2016 08:38:32 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/9/

2 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Error from server at http://127.0.0.1:45328: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: KeeperErrorCode = Session expired for /collections/acollectionafterbaddelete/state.json

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45328: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: KeeperErrorCode = Session expired for /collections/acollectionafterbaddelete/state.json
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:301)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:429)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:168)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=11007, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=11007, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:44594/lk_/c: Could not find collection : awholynewstresscollection_collection4_0
	at __randomizedtesting.SeedInfo.seed([F75C4F63CD64E917]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)




Build Log:
[...truncated 10710 lines...]
   [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/init-core-data-001
   [junit4]   2> 829949 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 829949 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /lk_/c
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 830019 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 830040 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 830050 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 830080 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-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_51552_hdfs____uay97k/webapp
   [junit4]   2> 830249 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 830510 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51552
   [junit4]   2> 830602 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 830604 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 830620 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-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_44346_datanode____.8e8ahw/webapp
   [junit4]   2> 830746 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 830984 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44346
   [junit4]   2> 831026 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 831028 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 831045 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-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_54024_datanode____.ydtbty/webapp
   [junit4]   2> 831105 INFO  (IPC Server handler 3 on 49523) [    ] BlockStateChange BLOCK* processReport: from storage DS-cc026750-f2a0-4286-9c13-16512c727616 node DatanodeRegistration(127.0.0.1, datanodeUuid=86628132-b2fb-4c87-b26a-fd9c865f60ac, infoPort=44346, ipcPort=48753, storageInfo=lv=-56;cid=testClusterID;nsid=830485145;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs
   [junit4]   2> 831106 INFO  (IPC Server handler 3 on 49523) [    ] BlockStateChange BLOCK* processReport: from storage DS-50eafdd2-4977-4b70-88b7-24a4f9efc32d node DatanodeRegistration(127.0.0.1, datanodeUuid=86628132-b2fb-4c87-b26a-fd9c865f60ac, infoPort=44346, ipcPort=48753, storageInfo=lv=-56;cid=testClusterID;nsid=830485145;c=0), blocks: 0, hasStaleStorages: false, processing time: 1 msecs
   [junit4]   2> 831187 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 831405 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54024
   [junit4]   2> 831606 INFO  (IPC Server handler 2 on 49523) [    ] BlockStateChange BLOCK* processReport: from storage DS-8f3823a8-ffbc-4834-b4a5-2523171b50db node DatanodeRegistration(127.0.0.1, datanodeUuid=79ceefbc-d026-4d84-9749-facaba4cb998, infoPort=54024, ipcPort=58599, storageInfo=lv=-56;cid=testClusterID;nsid=830485145;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 831607 INFO  (IPC Server handler 2 on 49523) [    ] BlockStateChange BLOCK* processReport: from storage DS-e1f61448-7c47-4f6c-8ba4-c73c7f809a09 node DatanodeRegistration(127.0.0.1, datanodeUuid=79ceefbc-d026-4d84-9749-facaba4cb998, infoPort=54024, ipcPort=58599, storageInfo=lv=-56;cid=testClusterID;nsid=830485145;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 831656 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 831656 INFO  (Thread-5978) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 831656 INFO  (Thread-5978) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 831756 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.ZkTestServer start zk server on port:45216
   [junit4]   2> 831757 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831757 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831760 INFO  (zkCallback-584-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5fde8f3b name:ZooKeeperConnection Watcher:127.0.0.1:45216 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 831764 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831764 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831792 INFO  (zkCallback-585-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d014d22 name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831793 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831793 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831793 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 831795 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 831797 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 831798 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 831800 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 831800 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 831803 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 831803 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 831805 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 831805 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 831807 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 831807 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 831808 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 831809 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 831810 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 831810 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 831812 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 831812 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 831814 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 831814 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 831816 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 831816 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 831818 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 831818 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 831820 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 831820 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 831822 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831822 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831824 INFO  (zkCallback-586-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c961a52 name:ZooKeeperConnection Watcher:127.0.0.1:45216 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831824 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831824 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831824 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 831826 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831826 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831827 INFO  (zkCallback-587-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e2f9d85 name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831827 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831828 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831828 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 831828 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 831830 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 831831 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 831832 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 831833 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 831834 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 831834 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 831836 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 831836 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 831838 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 831838 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 831840 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 831840 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 831842 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 831842 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 831843 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 831844 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 831845 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 831845 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 831847 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 831847 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 831849 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 831849 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 831936 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1
   [junit4]   2> 831938 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 831940 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@730c72a4{/lk_/c,null,AVAILABLE}
   [junit4]   2> 831940 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.ServerConnector Started ServerConnector@44eb3082{HTTP/1.1}{127.0.0.1:57032}
   [junit4]   2> 831940 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.Server Started @835021ms
   [junit4]   2> 831940 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=57032, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores, hostContext=/lk_/c, solr.data.dir=hdfs://localhost:49523/hdfs__localhost_49523__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001_tempDir-002_control_data}
   [junit4]   2> 831941 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 831941 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/'
   [junit4]   2> 831957 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831958 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831959 INFO  (zkCallback-588-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f3da8a9 name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831959 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831960 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831961 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 831961 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/solr.xml
   [junit4]   2> 831975 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores
   [junit4]   2> 831976 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer New CoreContainer 139777972
   [junit4]   2> 831976 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/]
   [junit4]   2> 831976 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/lib
   [junit4]   2> 831976 WARN  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/lib).
   [junit4]   2> 831985 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] 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> 831986 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 831987 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 831987 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 831987 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45216/solr
   [junit4]   2> 831987 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 831987 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 831988 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831989 INFO  (zkCallback-590-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1be91f82 name:ZooKeeperConnection Watcher:127.0.0.1:45216 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831990 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831990 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 831992 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 831993 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65910c64 name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 831993 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 831994 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 831996 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 831998 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 832000 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 832002 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 832004 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 832005 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 832007 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 832008 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 832009 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57032_lk_%2Fc
   [junit4]   2> 832009 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57032_lk_%2Fc
   [junit4]   2> 832011 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 832012 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 832013 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 832014 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000
   [junit4]   2> 832015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57032_lk_%2Fc
   [junit4]   2> 832015 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 832016 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer Overseer (id=95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) starting
   [junit4]   2> 832018 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 832026 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 832027 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 832027 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 832027 INFO  (OverseerCollectionProcessor-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 832051 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 832052 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 832053 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores
   [junit4]   2> 832054 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.SolrCore Created CoreDescriptor: {schema=schema.xml, configSetProperties=configsetprops.json, loadOnStartup=true, collection=control_collection, name=collection1, dataDir=data/, coreNodeName=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1/, shard=, transient=false, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1, config=solrconfig.xml}
   [junit4]   2> 832054 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1/
   [junit4]   2> 832054 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 832055 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 832055 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 832055 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 832055 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 832056 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 832056 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 832057 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:57032_lk_%2Fc",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:57032/lk_/c",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null} current state version: 0
   [junit4]   2> 832057 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:57032_lk_%2Fc",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:57032/lk_/c",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null}
   [junit4]   2> 832057 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 832058 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 832059 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 832060 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 833056 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 833056 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 833057 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 833057 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 833058 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 833058 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1/'
   [junit4]   2> 833074 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 833080 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 833093 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 833120 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 833122 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 833128 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 833375 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 833383 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 833385 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 833395 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 833398 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 833400 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 833401 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 833401 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 833401 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 833402 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 833402 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 833403 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 833403 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 833404 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
   [junit4]   2> 833404 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:49523/solr_hdfs_home
   [junit4]   2> 833404 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 833404 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 833404 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@59afd5be
   [junit4]   2> 833405 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 833454 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again.
   [junit4]   2> 838458 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data for locality metrics.
   [junit4]   2> 838458 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 838459 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data/index/
   [junit4]   2> 838470 WARN  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:49523/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 838470 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 838484 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics.
   [junit4]   2> 838484 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 838485 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=0.7900390625, floorSegmentMB=1.7802734375, forceMergeDeletesPctAllowed=24.610628177675558, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 838504 INFO  (IPC Server handler 0 on 49523) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58470 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-e1f61448-7c47-4f6c-8ba4-c73c7f809a09:NORMAL:127.0.0.1:53532|RBW], ReplicaUnderConstruction[[DISK]DS-cc026750-f2a0-4286-9c13-16512c727616:NORMAL:127.0.0.1:58470|RBW]]} size 0
   [junit4]   2> 838506 INFO  (IPC Server handler 1 on 49523) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53532 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-e1f61448-7c47-4f6c-8ba4-c73c7f809a09:NORMAL:127.0.0.1:53532|RBW], ReplicaUnderConstruction[[DISK]DS-cc026750-f2a0-4286-9c13-16512c727616:NORMAL:127.0.0.1:58470|RBW]]} size 0
   [junit4]   2> 838511 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=NRTCachingDirectory(HdfsDirectory@b7ea18fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24bf9a70; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 838512 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 838513 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in hdfs://localhost:49523/solr_hdfs_home/control_collection/core_node1/data/
   [junit4]   2> 838521 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 838521 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 838521 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 838521 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 838521 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 838522 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 838522 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 838522 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 838522 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 838525 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 838527 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 838528 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 838530 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 838534 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /config,/admin/plugins,/get,/admin/mbeans,/admin/threads,/update/csv,/admin/ping,/admin/luke,/update/json/docs,/admin/logging,/update,/admin/segments,/update/json,standard,/admin/properties,/replication,/schema,/admin/system,/admin/file
   [junit4]   2> 838536 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 838536 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
   [junit4]   2> 838536 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2
   [junit4]   2> 838550 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 838550 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 838552 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=60.4931640625, floorSegmentMB=1.435546875, forceMergeDeletesPctAllowed=5.793793122559253, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 838559 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=NRTCachingDirectory(HdfsDirectory@b7ea18fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@24bf9a70; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 838560 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 838560 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6e7b167c[collection1] main
   [junit4]   2> 838560 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 838561 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 838562 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 838562 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 838562 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 838562 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 838563 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 838563 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 838563 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 838564 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 838565 INFO  (searcherExecutor-1971-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e7b167c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 838567 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 838568 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index
   [junit4]   2> 838568 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 838568 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1522960773381357568
   [junit4]   2> 838568 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0 ms to seed version buckets with highest version 1522960773381357568
   [junit4]   2> 838568 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 838569 INFO  (coreLoadExecutor-1970-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 838569 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:57032/lk_/c collection:control_collection shard:shard1
   [junit4]   2> 838570 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 838574 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/95185047883481094-core_node1-n_0000000000
   [junit4]   2> 838575 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 838576 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 838577 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 838577 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 838577 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 838577 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57032/lk_/c/collection1/
   [junit4]   2> 838577 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 838577 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:57032/lk_/c/collection1/ has no replicas
   [junit4]   2> 838578 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57032/lk_/c/collection1/ shard1
   [junit4]   2> 838578 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 838578 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 838580 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 838584 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 838584 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:57032/lk_/c",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 838687 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 838689 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 838738 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:57032/lk_/c/collection1/ and leader is http://127.0.0.1:57032/lk_/c/collection1/
   [junit4]   2> 838738 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57032/lk_/c
   [junit4]   2> 838738 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 838738 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 838738 INFO  (coreZkRegister-1964-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc x:collection1 r:core_node1 c:control_collection s:shard1) [n:127.0.0.1:57032_lk_%2Fc c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 838740 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 838741 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:57032_lk_%2Fc",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "base_url":"http://127.0.0.1:57032/lk_/c",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null} current state version: 3
   [junit4]   2> 838742 INFO  (OverseerStateUpdate-95185047883481094-127.0.0.1:57032_lk_%2Fc-n_0000000000) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:57032_lk_%2Fc",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "base_url":"http://127.0.0.1:57032/lk_/c",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "roles":null}
   [junit4]   2> 838845 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 838846 INFO  (zkCallback-591-thread-1-processing-n:127.0.0.1:57032_lk_%2Fc) [n:127.0.0.1:57032_lk_%2Fc    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 839059 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 839060 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 839061 INFO  (zkCallback-593-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b5154f0 name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 839062 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 839062 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 839062 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 839064 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 839157 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/cores/collection1
   [junit4]   2> 839158 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001
   [junit4]   2> 839159 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 839183 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e7709a5{/lk_/c,null,AVAILABLE}
   [junit4]   2> 839184 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.ServerConnector Started ServerConnector@1c4a1bf3{HTTP/1.1}{127.0.0.1:45990}
   [junit4]   2> 839184 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.e.j.s.Server Started @842265ms
   [junit4]   2> 839184 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/cores, hostPort=45990, solr.data.dir=hdfs://localhost:49523/hdfs__localhost_49523__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001_tempDir-002_jetty1, hostContext=/lk_/c, solrconfig=solrconfig.xml}
   [junit4]   2> 839185 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 839185 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/'
   [junit4]   2> 839202 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 839202 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 839204 INFO  (zkCallback-594-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@372ee40d name:ZooKeeperConnection Watcher:127.0.0.1:45216/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 839204 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 839204 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 839205 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 839206 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/solr.xml
   [junit4]   2> 839221 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/cores
   [junit4]   2> 839222 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer New CoreContainer 1023050071
   [junit4]   2> 839222 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F75C4F63CD64E917-001/shard-1-001/]
   [junit4]   2> 839222 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F75C4F63CD64E917]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../.

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

, http://127.0.0.1:37235, http://127.0.0.1:45328, http://127.0.0.1:53746, http://127.0.0.1:44363]
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:355)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887)
   [junit4]    > Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:36113
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
   [junit4]    > 	... 7 more
   [junit4]    > Caused by: java.net.SocketTimeoutException: Read timed out
   [junit4]    > 	at java.net.SocketInputStream.socketRead0(Native Method)
   [junit4]    > 	at java.net.SocketInputStream.read(SocketInputStream.java:152)
   [junit4]    > 	at java.net.SocketInputStream.read(SocketInputStream.java:122)
   [junit4]    > 	at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160)
   [junit4]    > 	at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84)
   [junit4]    > 	at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273)
   [junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
   [junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
   [junit4]    > 	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
   [junit4]    > 	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
   [junit4]    > 	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
   [junit4]    > 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
   [junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
   [junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
   [junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685)
   [junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487)
   [junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882)
   [junit4]    > 	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
   [junit4]    > 	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
   [junit4]    > 	at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:465)
   [junit4]    > 	... 11 more
   [junit4]   2> 7264757 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[F75C4F63CD64E917]-worker) [n:127.0.0.1:53746_ c:awholynewstresscollection_collection1_1 s:shard1 r:core_node1 x:awholynewstresscollection_collection1_1_shard1_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F75C4F63CD64E917-001
   [junit4]   2> Jan 10, 2016 8:36:40 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l_dv=PostingsFormat(name=Asserting), other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=PostingsFormat(name=Asserting), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=MockRandom), a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{range_facet_i_dv=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=en_NZ, timezone=Etc/GMT-7
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=136773168,total=532152320
   [junit4]   2> NOTE: All tests run in this JVM: [TestJsonRequest, DocumentAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TriLevelCompositeIdRoutingTest, CacheHeaderTest, BadComponentTest, TestCoreContainer, TestComponentsName, TestShardHandlerFactory, FileUtilsTest, ShardRoutingTest, DistributedTermsComponentTest, SharedFSAutoReplicaFailoverTest, TestLMJelinekMercerSimilarityFactory, TestAuthorizationFramework, DistributedFacetPivotSmallTest, TestCloudManagedSchemaConcurrent, TestOrdValues, LeaderFailoverAfterPartitionTest, TestFreeTextSuggestions, ZkCLITest, AlternateDirectoryTest, TestMacros, OutOfBoxZkACLAndCredentialsProvidersTest, TestElisionMultitermQuery, TestMiniSolrCloudCluster, FacetPivotSmallTest, TestFileDictionaryLookup, AssignTest, TestHashPartitioner, HdfsDirectoryFactoryTest, TestAnalyzedSuggestions, DateRangeFieldTest, ScriptEngineTest, ResponseHeaderTest, VMParamsZkACLAndCredentialsProvidersTest, TestManagedResourceStorage, SpellCheckComponentTest, TestManagedStopFilterFactory, HLLSerializationTest, DeleteLastCustomShardedReplicaTest, AnalyticsQueryTest, TestMinMaxOnMultiValuedField, DocValuesMissingTest, TestAddFieldRealTimeGet, StatelessScriptUpdateProcessorFactoryTest, SpellPossibilityIteratorTest, TestCustomDocTransformer, BasicFunctionalityTest, NoCacheHeaderTest, BitVectorTest, ChaosMonkeySafeLeaderTest, TestSchemaVersionResource, CloudMLTQParserTest, TestFuzzyAnalyzedSuggestions, TestFiltering, BufferStoreTest, SliceStateTest, CollectionStateFormat2Test, SpellingQueryConverterTest, TestNoOpRegenerator, BigEndianAscendingWordDeserializerTest, ExplicitHLLTest, TestCharFilters, WordBreakSolrSpellCheckerTest, TestPHPSerializedResponseWriter, DistributedQueryComponentOptimizationTest, RemoteQueryErrorTest, TestScoreJoinQPNoScore, SuggesterTSTTest, BlockDirectoryTest, TestOmitPositions, UpdateParamsTest, DocumentBuilderTest, AnalysisAfterCoreReloadTest, SimpleMLTQParserTest, DistanceFunctionTest, SharedFSAutoReplicaFailoverUtilsTest, TestDistributedGrouping, HighlighterMaxOffsetTest, TestRTGBase, TestMaxScoreQueryParser, TestSearchPerf, DistributedFacetPivotLargeTest, TestDefaultStatsCache, EchoParamsTest, CoreAdminHandlerTest, TestReplicaProperties, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest]
   [junit4] Completed [533/533] on J1 in 5596.72s, 1 test, 1 error <<< FAILURES!

[...truncated 12 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 533 suites, 2104 tests, 2 errors, 26 ignored (14 assumptions)

Total time: 240 minutes 28 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



Mime
View raw message