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-6.x - Build # 96 - Still Failing
Date Sun, 19 Jun 2016 23:02:39 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/96/

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=14113, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:34052: collection already exists: awholynewstresscollection_collection5_2
	at __randomizedtesting.SeedInfo.seed([F9068B6B918E120E]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:403)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:356)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)


FAILED:  junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ObjectTracker found 8 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]

Stack Trace:
java.lang.AssertionError: ObjectTracker found 8 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
	at __randomizedtesting.SeedInfo.seed([F9068B6B918E120E]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertNull(Assert.java:551)
	at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:257)
	at sun.reflect.GeneratedMethodAccessor24.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=179996, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=179996, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:51555: collection already exists: awholynewstresscollection_collection1_0
	at __randomizedtesting.SeedInfo.seed([F9068B6B918E120E]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:403)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:356)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)




Build Log:
[...truncated 11590 lines...]
   [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/init-core-data-001
   [junit4]   2> 1007598 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1007598 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 1007633 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 1007640 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 1007655 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 1007666 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-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_51210_hdfs____.kulach/webapp
   [junit4]   2> 1007754 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 1008058 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51210
   [junit4]   2> 1008121 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 1008123 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 1008135 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-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_38803_datanode____.xy6tsz/webapp
   [junit4]   2> 1008212 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 1008783 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38803
   [junit4]   2> 1008832 WARN  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 1008834 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log jetty-6.1.26
   [junit4]   2> 1008844 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-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_51346_datanode____.vpqny8/webapp
   [junit4]   2> 1008921 INFO  (IPC Server handler 3 on 44179) [    ] BlockStateChange BLOCK* processReport: from storage DS-79e2367f-2a53-4b9c-96ee-99ecc0d2398a node DatanodeRegistration(127.0.0.1, datanodeUuid=204bc6ba-1da8-452c-b55e-659193caed04, infoPort=38803, ipcPort=45458, storageInfo=lv=-56;cid=testClusterID;nsid=1853110078;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 1008921 INFO  (IPC Server handler 3 on 44179) [    ] BlockStateChange BLOCK* processReport: from storage DS-a12fecbe-8977-4da2-b094-f0891fb902c9 node DatanodeRegistration(127.0.0.1, datanodeUuid=204bc6ba-1da8-452c-b55e-659193caed04, infoPort=38803, ipcPort=45458, storageInfo=lv=-56;cid=testClusterID;nsid=1853110078;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 1008957 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
   [junit4]   2> 1009220 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51346
   [junit4]   2> 1009281 INFO  (IPC Server handler 4 on 44179) [    ] BlockStateChange BLOCK* processReport: from storage DS-687944d0-ad12-4486-ab34-55bc32dd3908 node DatanodeRegistration(127.0.0.1, datanodeUuid=f3bc0d61-ed57-4617-81a5-2154be16648e, infoPort=51346, ipcPort=43833, storageInfo=lv=-56;cid=testClusterID;nsid=1853110078;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 1009281 INFO  (IPC Server handler 4 on 44179) [    ] BlockStateChange BLOCK* processReport: from storage DS-9d1bca52-e9e8-41ad-8920-d5f1dbe930f1 node DatanodeRegistration(127.0.0.1, datanodeUuid=f3bc0d61-ed57-4617-81a5-2154be16648e, infoPort=51346, ipcPort=43833, storageInfo=lv=-56;cid=testClusterID;nsid=1853110078;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 1009488 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1009489 INFO  (Thread-7576) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1009489 INFO  (Thread-7576) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1009589 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.ZkTestServer start zk server on port:36027
   [junit4]   2> 1009589 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009590 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009592 INFO  (zkCallback-1913-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f74fd98 name:ZooKeeperConnection Watcher:127.0.0.1:36027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009592 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009592 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009592 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1009594 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009594 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009596 INFO  (zkCallback-1914-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@656e50f6 name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009596 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009596 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009596 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 1009598 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 1009599 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 1009600 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 1009601 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 1009601 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 1009602 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 1009603 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 1009604 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1009604 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1009605 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 1009605 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 1009607 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 1009607 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 1009608 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 1009608 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 1009609 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1009609 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 1009611 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1009611 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1009612 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1009612 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1009613 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1009614 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 1009615 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 1009615 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 1009617 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009617 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009618 INFO  (zkCallback-1915-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@276ea028 name:ZooKeeperConnection Watcher:127.0.0.1:36027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009618 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009618 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009618 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 1009619 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009620 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009620 INFO  (zkCallback-1916-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54c94f2f name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009620 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009621 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009621 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 1009621 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 1009622 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 1009622 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 1009624 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 1009624 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 1009625 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1009625 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1009626 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 1009626 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 1009627 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 1009627 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 1009628 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 1009628 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 1009629 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1009629 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 1009630 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1009630 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1009631 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 1009631 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 1009632 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 1009632 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 1009633 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 1009633 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 1009699 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores/collection1
   [junit4]   2> 1009700 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@39975273{/,null,AVAILABLE}
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.ServerConnector Started ServerConnector@619cb287{HTTP/1.1,[http/1.1]}{127.0.0.1:53619}
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.Server Started @1012942ms
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:44179/hdfs__localhost_44179__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001_tempDir-002_control_data, hostContext=/, hostPort=53619, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores}
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001'
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1009702 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1009703 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009703 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009704 INFO  (zkCallback-1917-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d939a83 name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009704 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009705 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009705 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1009705 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/solr.xml
   [junit4]   2> 1009709 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores
   [junit4]   2> 1009709 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CoreContainer New CoreContainer 373200867
   [junit4]   2> 1009709 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001]
   [junit4]   2> 1009709 WARN  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/lib
   [junit4]   2> 1009709 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] 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> 1009710 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1009710 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1009710 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1009711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36027/solr
   [junit4]   2> 1009711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 1009711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1009711 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009713 INFO  (zkCallback-1920-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14c4424d name:ZooKeeperConnection Watcher:127.0.0.1:36027 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009714 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009714 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1009715 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$57/1645707539@dac560f
   [junit4]   2> 1009718 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1009721 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@db889ec name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1009721 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1009721 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 1009722 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 1009724 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 1009724 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 1009725 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 1009726 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 1009726 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 1009727 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 1009728 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 1009728 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1009728 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1009729 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1009730 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 1009730 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 1009731 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1009731 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96100236532252678-127.0.0.1:53619_-n_0000000000
   [junit4]   2> 1009731 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53619_
   [junit4]   2> 1009731 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 1009732 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer Overseer (id=96100236532252678-127.0.0.1:53619_-n_0000000000) starting
   [junit4]   2> 1009733 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 1009737 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 1009737 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1009749 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53619_
   [junit4]   2> 1009750 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:53619_
   [junit4]   2> 1009750 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 1009751 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
   [junit4]   2> 1009751 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1009758 INFO  (OverseerCollectionConfigSetProcessor-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 1009759 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1009759 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1009760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores
   [junit4]   2> 1009760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 1009760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores/collection1
   [junit4]   2> 1009760 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1009761 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1009761 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 1009761 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 1009761 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [n:127.0.0.1:53619_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1009761 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible
   [junit4]   2> 1009762 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:53619",
   [junit4]   2>   "node_name":"127.0.0.1:53619_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1009762 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:53619",
   [junit4]   2>   "node_name":"127.0.0.1:53619_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1009762 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection]
   [junit4]   2> 1009762 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 1009762 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 1009763 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 1009763 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] 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> 1010763 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 1010763 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores/collection1'
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1010764 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1010765 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 1010769 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1010771 WARN  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 1010772 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.2.0
   [junit4]   2> 1010778 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1010782 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1010859 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1010863 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1010864 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1010869 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1010871 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1010872 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1010872 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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-6.x/solr/build/solr-core/test/J1
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:44179/solr_hdfs_home
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 1010873 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/control-001/cores/collection1], dataDir=[hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/]
   [junit4]   2> 1010874 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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@6b111841
   [junit4]   2> 1010874 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 1010885 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 1010889 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index/
   [junit4]   2> 1010895 WARN  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 1010895 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 1010913 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 1010913 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1414583399, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 1011089 INFO  (IPC Server handler 5 on 44179) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35424 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-a12fecbe-8977-4da2-b094-f0891fb902c9:NORMAL:127.0.0.1:48408|RBW], ReplicaUnderConstruction[[DISK]DS-687944d0-ad12-4486-ab34-55bc32dd3908:NORMAL:127.0.0.1:35424|RBW]]} size 71
   [junit4]   2> 1011093 INFO  (IPC Server handler 8 on 44179) [    ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:48408 is added to blk_1073741825_1001 size 71
   [junit4]   2> 1011481 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@79f00f1d; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 1011481 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1011482 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53619_ 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:44179/solr_hdfs_home/control_collection/core_node1/data/
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011484 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011484 WARN  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 1011485 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011486 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011487 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011487 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1011489 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 1011489 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011489 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011489 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1011489 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
   [junit4]   2> 1011501 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1011501 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1011502 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5831232526589275]
   [junit4]   2> 1011506 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@79f00f1d; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 1011506 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1011506 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@336dced2[collection1] main]
   [junit4]   2> 1011506 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011507 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011507 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011507 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1011507 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1011507 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011508 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011508 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011508 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1011508 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1011509 INFO  (searcherExecutor-2596-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@336dced2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1011509 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011509 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 1011509 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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> 1011509 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ 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 1537603786633117696
   [junit4]   2> 1011511 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1537603786633117696
   [junit4]   2> 1011511 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1011511 INFO  (coreLoadExecutor-2595-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1011511 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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:53619 collection:control_collection shard:shard1
   [junit4]   2> 1011512 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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> 1011514 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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/96100236532252678-core_node1-n_0000000000
   [junit4]   2> 1011514 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000
   [junit4]   2> 1011515 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1011515 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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> 1011515 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53619/collection1/
   [junit4]   2> 1011515 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1011515 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:53619/collection1/ has no replicas
   [junit4]   2> 1011515 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] 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> 1011516 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 1011516 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] 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> 1011517 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/96100236532252678-core_node1-n_0000000000
   [junit4]   2> 1011518 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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:53619/collection1/ shard1
   [junit4]   2> 1011518 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] 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:53619",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 1011620 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] 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> 1011669 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:53619/collection1/ and leader is http://127.0.0.1:53619/collection1/
   [junit4]   2> 1011669 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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:53619
   [junit4]   2> 1011669 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1011669 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1011669 INFO  (coreZkRegister-2589-thread-1-processing-n:127.0.0.1:53619_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:53619_ 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> 1011671 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "dataDir":"hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:53619",
   [junit4]   2>   "node_name":"127.0.0.1:53619_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "ulogDir":"hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/tlog"} current state version: 3
   [junit4]   2> 1011671 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "dataDir":"hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:53619",
   [junit4]   2>   "node_name":"127.0.0.1:53619_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "ulogDir":"hdfs://localhost:44179/solr_hdfs_home/control_collection/core_node1/data/tlog"}
   [junit4]   2> 1011767 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1011767 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1011768 INFO  (zkCallback-1925-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fa8b143 name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1011768 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1011769 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1011769 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 1011769 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 1011770 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1011771 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 1011771 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 1011771 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1011772 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1011772 INFO  (zkCallback-1926-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f4ae3c7 name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1011773 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1011773 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1011778 INFO  (zkCallback-1925-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1011778 INFO  (zkCallback-1921-thread-1-processing-n:127.0.0.1:53619_) [n:127.0.0.1:53619_    ] 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> 1011780 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 4
   [junit4]   2> 1011781 INFO  (OverseerStateUpdate-96100236532252678-127.0.0.1:53619_-n_0000000000) [n:127.0.0.1:53619_    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 1011834 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001/cores/collection1
   [junit4]   2> 1011835 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001
   [junit4]   2> 1011835 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1011836 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75029db{/,null,AVAILABLE}
   [junit4]   2> 1011836 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.ServerConnector Started ServerConnector@32da63bd{HTTP/1.1,[http/1.1]}{127.0.0.1:52086}
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.e.j.s.Server Started @1015077ms
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:44179/hdfs__localhost_44179__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=52086, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001/cores}
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001'
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 1011837 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1011839 INFO  (zkCallback-1927-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@545d2be1 name:ZooKeeperConnection Watcher:127.0.0.1:36027/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 1011839 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1011839 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 1011840 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 1011840 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001/solr.xml
   [junit4]   2> 1011843 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_F9068B6B918E120E-001/shard-1-001/cores
   [junit4]   2> 1011843 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CoreContainer New CoreContainer 1481041463
   [junit4]   2> 1011843 INFO  (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[F9068B6B918E120E]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [in

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

s://127.0.0.1:33961, https://127.0.0.1:51555, https://127.0.0.1:50449, https://127.0.0.1:40823, https://127.0.0.1:43691]
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:383)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934)
   [junit4]    > 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599)
   [junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620)
   [junit4]    > 	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)
   [junit4]    > Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:50449
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:601)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:403)
   [junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:356)
   [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.socketRead(SocketInputStream.java:116)
   [junit4]    > 	at java.net.SocketInputStream.read(SocketInputStream.java:170)
   [junit4]    > 	at java.net.SocketInputStream.read(SocketInputStream.java:141)
   [junit4]    > 	at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
   [junit4]    > 	at sun.security.ssl.InputRecord.read(InputRecord.java:503)
   [junit4]    > 	at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973)
   [junit4]    > 	at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:930)
   [junit4]    > 	at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
   [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:495)
   [junit4]    > 	... 11 more
   [junit4]   2> 3286320 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[F9068B6B918E120E]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {other_tl1=PostingsFormat(name=MockRandom), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Memory doPackFST= true), intDefault=PostingsFormat(name=MockRandom), a_i1=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= true), text=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Memory doPackFST= true), timestamp=PostingsFormat(name=Memory doPackFST= true)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1818, maxMBSortInHeap=6.728991094923747, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=ar-LB, timezone=Europe/Vilnius
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=257798728,total=486014976
   [junit4]   2> NOTE: All tests run in this JVM: [TestPseudoReturnFields, BlockDirectoryTest, PeerSyncTest, TestPerFieldSimilarityWithDefaultOverride, TestBulkSchemaConcurrent, JavabinLoaderTest, SortSpecParsingTest, DeleteStatusTest, SolrRequestParserTest, TestDistribIDF, CursorPagingTest, DefaultValueUpdateProcessorTest, TestCSVLoader, TestConfigSetsAPIExclusivity, TlogReplayBufferedWhileIndexingTest, SuggestComponentContextFilterQueryTest, HdfsBasicDistributedZk2Test, TestLegacyFieldCache, LeaderInitiatedRecoveryOnCommitTest, TestReRankQParserPlugin, TestMacros, TestJsonFacets, TestStressLiveNodes, SSLMigrationTest, TestFreeTextSuggestions, TestBulkSchemaAPI, CacheHeaderTest, HdfsChaosMonkeySafeLeaderTest, TestSolr4Spatial, HLLSerializationTest, DistributedFacetPivotWhiteBoxTest, TestManagedResource, ZkNodePropsTest, TestQueryWrapperFilter, MultiThreadedOCPTest, TestSolr4Spatial2, TestFastOutputStream, CoreAdminCreateDiscoverTest, TestLuceneMatchVersion, EchoParamsTest, TestFieldCollectionResource, TestNRTOpen, TestMiniSolrCloudClusterSSL, ResourceLoaderTest, TestSearcherReuse, TestDocSet, AsyncCallRequestStatusResponseTest, SharedFSAutoReplicaFailoverTest, UUIDFieldTest, TestExpandComponent, LeaderElectionIntegrationTest, CurrencyFieldOpenExchangeTest, BooleanFieldTest, PreAnalyzedFieldTest, TestImplicitCoreProperties, TestSortByMinMaxFunction, TestScoreJoinQPNoScore, TestManagedSchemaAPI, TestObjectReleaseTracker, TestBadConfig, OverseerCollectionConfigSetProcessorTest, QueryElevationComponentTest, TestRemoteStreaming, TestLeaderElectionZkExpiry, TolerantUpdateProcessorTest, TestClusterStateMutator, TestRTGBase, TestDistributedSearch, TestSubQueryTransformer, DistributedSpellCheckComponentTest, ConnectionReuseTest, BlockJoinFacetRandomTest, TestFieldCacheSortRandom, BlobRepositoryCloudTest, TestUninvertingReader, CdcrUpdateLogTest, TestOmitPositions, TestSolrQueryParserDefaultOperatorResource, TestLMDirichletSimilarityFactory, TestMiniSolrCloudCluster, TestLRUCache, WrapperMergePolicyFactoryTest, PolyFieldTest, SynonymTokenizerTest, TestReplicationHandler, TestRebalanceLeaders, TestConfigSetProperties, TestDFISimilarityFactory, AddSchemaFieldsUpdateProcessorFactoryTest, SystemInfoHandlerTest, JSONWriterTest, TestUseDocValuesAsStored2, HdfsLockFactoryTest, DistributedQueryElevationComponentTest, TestRequestStatusCollectionAPI, SOLR749Test, TestSchemaSimilarityResource, TestJoin, SyncSliceTest, TestFunctionQuery, SignatureUpdateProcessorFactoryTest, CollectionsAPIDistributedZkTest]
   [junit4] Completed [288/615 (3!)] on J0 in 522.52s, 1 test, 1 error <<< FAILURES!

[...truncated 1009 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:531: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: There were test failures: 615 suites (10 ignored), 2591 tests, 1 suite-level error, 2 errors, 33 ignored (18 assumptions) [seed: F9068B6B918E120E]

Total time: 255 minutes 59 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